builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1493168626.85 results: success (0) buildid: 20170425163425 builduid: ff4f33c4f9c0404e826307343053285f revision: 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:46.850177) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:46.850754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:46.851070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:46.880841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:46.881248) ========= 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.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-04-25 18:03:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.3M=0.001s 2017-04-25 18:03:47 (14.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.171828 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:47.080600) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:47.081072) ========= 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.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.028170 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:47.365751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-25 18:03:47.366149) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 --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 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-04-25 18:03:47,481 truncating revision to first 12 chars 2017-04-25 18:03:47,481 Setting DEBUG logging. 2017-04-25 18:03:47,481 attempt 1/10 2017-04-25 18:03:47,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/68fe3cab6553?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-25 18:03:49,701 unpacking tar archive at: mozilla-beta-68fe3cab6553/testing/mozharness/ program finished with exit code 0 elapsedTime=2.490989 ========= master_lag: 1.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-25 18:03:51.379035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:51.379401) ========= script_repo_revision: 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:51.379841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:51.380144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 18:03:52.309657) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-25 18:03:52.310053) ========= /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.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:03:52 INFO - MultiFileLogger online at 20170425 18:03:52 in /builds/slave/test 18:03:52 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 18:03:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:52 INFO - {'append_to_log': False, 18:03:52 INFO - 'base_work_dir': '/builds/slave/test', 18:03:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:03:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:52 INFO - 'branch': 'Mozilla-Beta', 18:03:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:52 INFO - 'config_files': ('talos/mac_config.py',), 18:03:52 INFO - 'default_actions': ('clobber', 18:03:52 INFO - 'read-buildbot-config', 18:03:52 INFO - 'download-and-extract', 18:03:52 INFO - 'populate-webroot', 18:03:52 INFO - 'create-virtualenv', 18:03:52 INFO - 'install', 18:03:52 INFO - 'run-tests'), 18:03:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:52 INFO - 'download_minidump_stackwalk': True, 18:03:52 INFO - 'download_symbols': 'ondemand', 18:03:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:03:52 INFO - 'tooltool.py': '/tools/tooltool.py', 18:03:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:03:52 INFO - '/tools/misc-python/virtualenv.py')}, 18:03:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:52 INFO - 'gecko_profile': False, 18:03:52 INFO - 'gecko_profile_interval': 0, 18:03:52 INFO - 'installer_path': 'installer.exe', 18:03:52 INFO - 'log_level': 'info', 18:03:52 INFO - 'log_name': 'talos', 18:03:52 INFO - 'log_to_console': True, 18:03:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:03:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:03:52 INFO - 'opt_config_files': (), 18:03:52 INFO - 'pip_index': False, 18:03:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:52 INFO - 'cmd': ('bash', 18:03:52 INFO - '-c', 18:03:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:52 INFO - 'enabled': True, 18:03:52 INFO - 'halt_on_failure': False, 18:03:52 INFO - 'name': 'check_screen_resolution'},), 18:03:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:52 INFO - 'cmd': ('bash', 18:03:52 INFO - '-c', 18:03:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:52 INFO - 'enabled': True, 18:03:52 INFO - 'halt_on_failure': False, 18:03:52 INFO - 'name': 'check_screen_resolution'},), 18:03:52 INFO - 'run_cmd_checks_enabled': True, 18:03:52 INFO - 'suite': 'dromaeojs-e10s', 18:03:52 INFO - 'system_bits': '32', 18:03:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:52 INFO - 'title': 't-yosemite-r7-0068', 18:03:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:52 INFO - 'use_talos_json': True, 18:03:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:52 INFO - 'work_dir': 'build'} 18:03:52 INFO - [mozharness: 2017-04-26 01:03:52.535850Z] Running clobber step. 18:03:52 INFO - Running pre-action listener: _resource_record_pre_action 18:03:52 INFO - Running main action method: clobber 18:03:52 INFO - rmtree: /builds/slave/test/build 18:03:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:55 INFO - Running post-action listener: _resource_record_post_action 18:03:55 INFO - [mozharness: 2017-04-26 01:03:55.120684Z] Finished clobber step (success) 18:03:55 INFO - [mozharness: 2017-04-26 01:03:55.120836Z] Running read-buildbot-config step. 18:03:55 INFO - Running pre-action listener: _resource_record_pre_action 18:03:55 INFO - Running main action method: read_buildbot_config 18:03:55 INFO - Using buildbot properties: 18:03:55 INFO - { 18:03:55 INFO - "project": "", 18:03:55 INFO - "product": "firefox", 18:03:55 INFO - "script_repo_revision": "production", 18:03:55 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 18:03:55 INFO - "repository": "", 18:03:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 18:03:55 INFO - "buildid": "20170425163425", 18:03:55 INFO - "pgo_build": "False", 18:03:55 INFO - "basedir": "/builds/slave/test", 18:03:55 INFO - "buildnumber": 11, 18:03:55 INFO - "slavename": "t-yosemite-r7-0068", 18:03:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:03:55 INFO - "platform": "yosemite_r7", 18:03:55 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:03:55 INFO - "branch": "mozilla-beta", 18:03:55 INFO - "repo_path": "releases/mozilla-beta", 18:03:55 INFO - "revision": "68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6", 18:03:55 INFO - "stage_platform": "macosx64", 18:03:55 INFO - "builduid": "ff4f33c4f9c0404e826307343053285f", 18:03:55 INFO - "slavebuilddir": "test" 18:03:55 INFO - } 18:03:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 18:03:55 INFO - Running post-action listener: _resource_record_post_action 18:03:55 INFO - [mozharness: 2017-04-26 01:03:55.124132Z] Finished read-buildbot-config step (success) 18:03:55 INFO - [mozharness: 2017-04-26 01:03:55.124238Z] Running download-and-extract step. 18:03:55 INFO - Running pre-action listener: _resource_record_pre_action 18:03:55 INFO - Running main action method: download_and_extract 18:03:55 INFO - mkdir: /builds/slave/test/build/tests 18:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:55 INFO - trying https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 18:03:55 INFO - Downloading https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 18:03:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 18:03:55 INFO - Downloaded 1165 bytes. 18:03:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 18:03:55 INFO - Using the following test package requirements: 18:03:55 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:03:55 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 18:03:55 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 18:03:55 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:03:55 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 18:03:55 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:03:55 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 18:03:55 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 18:03:55 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 18:03:55 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:03:55 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 18:03:55 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 18:03:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:03:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:03:55 INFO - Fetch https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:03:57 INFO - Expected file size: 51223550 18:03:57 INFO - Obtained file size: 51223550 18:04:13 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 18:04:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:04:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:04:13 INFO - Fetch https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:04:15 INFO - Expected file size: 51223550 18:04:15 INFO - Obtained file size: 51223550 18:04:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 18:04:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 18:04:30 INFO - Fetch https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 18:04:31 INFO - Expected file size: 14404449 18:04:31 INFO - Obtained file size: 14404449 18:04:31 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')]} 18:04:31 INFO - trying https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:04:31 INFO - Downloading https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 18:04:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 18:04:33 INFO - Downloaded 59531849 bytes. 18:04:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:04:33 INFO - mkdir: /builds/slave/test/properties 18:04:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:33 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:33 INFO - Contents: 18:04:33 INFO - build_url:https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:04:34 INFO - Running post-action listener: _resource_record_post_action 18:04:34 INFO - Running post-action listener: set_extra_try_arguments 18:04:34 INFO - [mozharness: 2017-04-26 01:04:34.419622Z] Finished download-and-extract step (success) 18:04:34 INFO - [mozharness: 2017-04-26 01:04:34.419765Z] Running populate-webroot step. 18:04:34 INFO - Running pre-action listener: _resource_record_pre_action 18:04:34 INFO - Running main action method: populate_webroot 18:04:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:34 INFO - '--tppagecycles', 18:04:34 INFO - '7'], 18:04:34 INFO - 'tests': ['tsvgm']}}, 18:04:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['damp', 'tps']}, 18:04:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'tests': ['damp', 'tps']}, 18:04:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['dromaeo_dom']}, 18:04:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['basic_compositor_video']}, 18:04:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:04:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['a11yr', 18:04:34 INFO - 'ts_paint', 18:04:34 INFO - 'tpaint', 18:04:34 INFO - 'sessionrestore', 18:04:34 INFO - 'sessionrestore_no_auto_restore', 18:04:34 INFO - 'tabpaint']}, 18:04:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['tsvgx', 18:04:34 INFO - 'tsvgr_opacity', 18:04:34 INFO - 'tart', 18:04:34 INFO - 'tscrollx', 18:04:34 INFO - 'cart', 18:04:34 INFO - 'tsvg_static']}, 18:04:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:34 INFO - 'tsvgr_opacity', 18:04:34 INFO - 'tart', 18:04:34 INFO - 'tscrollx', 18:04:34 INFO - 'cart', 18:04:34 INFO - 'tsvg_static']}, 18:04:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'talos_options': ['--disable-e10s'], 18:04:34 INFO - 'tests': ['tp5o']}, 18:04:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:04:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'talos_options': ['--disable-e10s', 18:04:34 INFO - '--xperf_path', 18:04:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:34 INFO - 'tests': ['tp5n']}, 18:04:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:04:34 INFO - 'talos_options': ['--xperf_path', 18:04:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:34 INFO - 'tests': ['tp5n']}}, 18:04:34 INFO - 'talos.zip': {'path': '', 18:04:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:34 INFO - Running post-action listener: _resource_record_post_action 18:04:34 INFO - [mozharness: 2017-04-26 01:04:34.438494Z] Finished populate-webroot step (success) 18:04:34 INFO - [mozharness: 2017-04-26 01:04:34.438576Z] Running create-virtualenv step. 18:04:34 INFO - Running pre-action listener: _resource_record_pre_action 18:04:34 INFO - Running main action method: create_virtualenv 18:04:34 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:34 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 18:04:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:34 INFO - Using real prefix '/tools/python27' 18:04:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:35 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:38 INFO - Installing pip.................done. 18:04:38 INFO - Return code: 0 18:04:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:04:38 INFO - Reading from file tmpfile_stdout 18:04:38 INFO - Output received: 18:04:38 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) 18:04:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:38 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')]} 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:38 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')]} 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:38 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:38 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 18:04:38 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 18:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:38 INFO - 'HOME': '/Users/cltbld', 18:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:38 INFO - 'LOGNAME': 'cltbld', 18:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:38 INFO - 'MOZ_NO_REMOTE': '1', 18:04:38 INFO - 'NO_EM_RESTART': '1', 18:04:38 INFO - 'PAGER': '/bin/cat', 18:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:38 INFO - 'PWD': '/builds/slave/test', 18:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:38 INFO - 'SHELL': '/bin/bash', 18:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:38 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:38 INFO - 'USER': 'cltbld', 18:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:38 INFO - 'XPC_FLAGS': '0x0', 18:04:38 INFO - 'XPC_SERVICE_NAME': '0', 18:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:38 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 18:04:38 INFO - Cleaning up... 18:04:38 INFO - Return code: 0 18:04:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:38 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')]} 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:38 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')]} 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:38 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:38 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 18:04:38 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 18:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:38 INFO - 'HOME': '/Users/cltbld', 18:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:38 INFO - 'LOGNAME': 'cltbld', 18:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:38 INFO - 'MOZ_NO_REMOTE': '1', 18:04:38 INFO - 'NO_EM_RESTART': '1', 18:04:38 INFO - 'PAGER': '/bin/cat', 18:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:38 INFO - 'PWD': '/builds/slave/test', 18:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:38 INFO - 'SHELL': '/bin/bash', 18:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:38 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:38 INFO - 'USER': 'cltbld', 18:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:38 INFO - 'XPC_FLAGS': '0x0', 18:04:38 INFO - 'XPC_SERVICE_NAME': '0', 18:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:38 INFO - Downloading/unpacking psutil>=3.1.1 18:04:38 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 18:04:38 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 18:04:38 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 18:04:38 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 18:04:38 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 18:04:38 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 18:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:42 INFO - Installing collected packages: psutil 18:04:42 INFO - Running setup.py install for psutil 18:04:42 INFO - building 'psutil._psutil_osx' extension 18:04:42 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 18:04:43 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 18:04:43 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 18:04:43 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 18:04:43 INFO - building 'psutil._psutil_posix' extension 18:04:43 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 18:04:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:04:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:04:43 INFO - ^ 18:04:43 INFO - 1 warning generated. 18:04:43 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 18:04:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:43 INFO - Successfully installed psutil 18:04:43 INFO - Cleaning up... 18:04:43 INFO - Return code: 0 18:04:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:44 INFO - 'HOME': '/Users/cltbld', 18:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:44 INFO - 'LOGNAME': 'cltbld', 18:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:44 INFO - 'MOZ_NO_REMOTE': '1', 18:04:44 INFO - 'NO_EM_RESTART': '1', 18:04:44 INFO - 'PAGER': '/bin/cat', 18:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:44 INFO - 'PWD': '/builds/slave/test', 18:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:44 INFO - 'SHELL': '/bin/bash', 18:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:44 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:44 INFO - 'USER': 'cltbld', 18:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:44 INFO - 'XPC_FLAGS': '0x0', 18:04:44 INFO - 'XPC_SERVICE_NAME': '0', 18:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:04:48 INFO - Installing collected packages: mozsystemmonitor 18:04:48 INFO - Running setup.py install for mozsystemmonitor 18:04:48 INFO - Successfully installed mozsystemmonitor 18:04:48 INFO - Cleaning up... 18:04:48 INFO - Return code: 0 18:04:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:04:48 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')]} 18:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:48 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')]} 18:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:48 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:48 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 18:04:48 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 18:04:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:48 INFO - 'HOME': '/Users/cltbld', 18:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:48 INFO - 'LOGNAME': 'cltbld', 18:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:48 INFO - 'MOZ_NO_REMOTE': '1', 18:04:48 INFO - 'NO_EM_RESTART': '1', 18:04:48 INFO - 'PAGER': '/bin/cat', 18:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:48 INFO - 'PWD': '/builds/slave/test', 18:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:48 INFO - 'SHELL': '/bin/bash', 18:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:48 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:48 INFO - 'USER': 'cltbld', 18:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:48 INFO - 'XPC_FLAGS': '0x0', 18:04:48 INFO - 'XPC_SERVICE_NAME': '0', 18:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:04:48 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 18:04:48 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 18:04:48 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 18:04:48 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 18:04:48 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 18:04:48 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 18:04:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:04:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:04:51 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 18:04:51 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 18:04:51 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 18:04:51 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 18:04:51 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 18:04:51 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 18:04:51 INFO - Downloading functools32-3.2.3-2.tar.gz 18:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:04:51 INFO - warning: no files found matching '*.txt' 18:04:51 INFO - no previously-included directories found matching 'build' 18:04:51 INFO - no previously-included directories found matching 'dist' 18:04:51 INFO - no previously-included directories found matching '.git*' 18:04:51 INFO - Installing collected packages: jsonschema, functools32 18:04:51 INFO - Running setup.py install for functools32 18:04:51 INFO - warning: no files found matching '*.txt' 18:04:51 INFO - no previously-included directories found matching 'build' 18:04:51 INFO - no previously-included directories found matching 'dist' 18:04:51 INFO - no previously-included directories found matching '.git*' 18:04:51 INFO - Successfully installed jsonschema functools32 18:04:51 INFO - Cleaning up... 18:04:51 INFO - Return code: 0 18:04:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:04:51 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')]} 18:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:51 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')]} 18:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:51 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:51 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 18:04:51 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 18:04:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:51 INFO - 'HOME': '/Users/cltbld', 18:04:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:51 INFO - 'LOGNAME': 'cltbld', 18:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:51 INFO - 'MOZ_NO_REMOTE': '1', 18:04:51 INFO - 'NO_EM_RESTART': '1', 18:04:51 INFO - 'PAGER': '/bin/cat', 18:04:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:51 INFO - 'PWD': '/builds/slave/test', 18:04:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:51 INFO - 'SHELL': '/bin/bash', 18:04:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:51 INFO - 'USER': 'cltbld', 18:04:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:51 INFO - 'XPC_FLAGS': '0x0', 18:04:51 INFO - 'XPC_SERVICE_NAME': '0', 18:04:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:04:52 INFO - Cleaning up... 18:04:52 INFO - Return code: 0 18:04:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:52 INFO - 'HOME': '/Users/cltbld', 18:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:52 INFO - 'LOGNAME': 'cltbld', 18:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:52 INFO - 'MOZ_NO_REMOTE': '1', 18:04:52 INFO - 'NO_EM_RESTART': '1', 18:04:52 INFO - 'PAGER': '/bin/cat', 18:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:52 INFO - 'PWD': '/builds/slave/test', 18:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:52 INFO - 'SHELL': '/bin/bash', 18:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:52 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:52 INFO - 'USER': 'cltbld', 18:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:52 INFO - 'XPC_FLAGS': '0x0', 18:04:52 INFO - 'XPC_SERVICE_NAME': '0', 18:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:52 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:55 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:56 INFO - Downloading docopt-0.6.1.tar.gz 18:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:56 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:56 INFO - Running setup.py install for blobuploader 18:04:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:56 INFO - Running setup.py install for requests 18:04:57 INFO - Running setup.py install for docopt 18:04:57 INFO - Successfully installed blobuploader requests docopt 18:04:57 INFO - Cleaning up... 18:04:57 INFO - Return code: 0 18:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:57 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')]} 18:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:57 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')]} 18:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:57 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:04:57 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 18:04:57 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 18:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:57 INFO - 'HOME': '/Users/cltbld', 18:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:57 INFO - 'LOGNAME': 'cltbld', 18:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:57 INFO - 'MOZ_NO_REMOTE': '1', 18:04:57 INFO - 'NO_EM_RESTART': '1', 18:04:57 INFO - 'PAGER': '/bin/cat', 18:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:57 INFO - 'PWD': '/builds/slave/test', 18:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:57 INFO - 'SHELL': '/bin/bash', 18:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:04:57 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:57 INFO - 'USER': 'cltbld', 18:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:57 INFO - 'XPC_FLAGS': '0x0', 18:04:57 INFO - 'XPC_SERVICE_NAME': '0', 18:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-1dk8rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PpDUDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0nYRy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZG7RzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0rLtMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-KM5fRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-awo3rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8fu2UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-oYhBcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_zBuOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZO5bv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-AODDZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-wPBtyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-DjP0vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-JZg0SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yWayvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lGSkO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:04:59 INFO - Running setup.py install for manifestparser 18:04:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:04:59 INFO - Running setup.py install for mozcrash 18:04:59 INFO - Running setup.py install for mozdebug 18:04:59 INFO - Running setup.py install for mozdevice 18:05:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozfile 18:05:00 INFO - Running setup.py install for mozhttpd 18:05:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozinfo 18:05:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozInstall 18:05:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozleak 18:05:00 INFO - Running setup.py install for mozlog 18:05:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for moznetwork 18:05:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozprocess 18:05:01 INFO - Running setup.py install for mozprofile 18:05:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozrunner 18:05:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozscreenshot 18:05:01 INFO - Running setup.py install for moztest 18:05:01 INFO - Running setup.py install for mozversion 18:05:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:02 INFO - Cleaning up... 18:05:02 INFO - Return code: 0 18:05:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:02 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')]} 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:02 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')]} 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:02 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:05:02 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 18:05:02 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 18:05:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:05:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:02 INFO - 'HOME': '/Users/cltbld', 18:05:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:02 INFO - 'LOGNAME': 'cltbld', 18:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:02 INFO - 'MOZ_NO_REMOTE': '1', 18:05:02 INFO - 'NO_EM_RESTART': '1', 18:05:02 INFO - 'PAGER': '/bin/cat', 18:05:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:02 INFO - 'PWD': '/builds/slave/test', 18:05:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:02 INFO - 'SHELL': '/bin/bash', 18:05:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:05:02 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:05:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:02 INFO - 'USER': 'cltbld', 18:05:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:02 INFO - 'XPC_FLAGS': '0x0', 18:05:02 INFO - 'XPC_SERVICE_NAME': '0', 18:05:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-P2Duen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-c6P8k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mKBlKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-COE6UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_n8wRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Gk8Drx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:02 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)) 18:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-kf3TrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UODI1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YMI99c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-knVz2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-n8iq9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-M9LpoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-I04cHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8Stb5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-MhfHPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-46SN95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:04 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_RQX9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:04 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)) 18:05:04 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)) 18:05:04 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)) 18:05:04 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)) 18:05:04 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)) 18:05:04 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)) 18:05:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:06 INFO - Downloading blessings-1.6.tar.gz 18:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:06 INFO - Installing collected packages: blessings 18:05:06 INFO - Running setup.py install for blessings 18:05:06 INFO - Successfully installed blessings 18:05:06 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:07 INFO - Reading from file tmpfile_stdout 18:05:07 INFO - Current package versions: 18:05:07 INFO - blessings == 1.6 18:05:07 INFO - blobuploader == 1.2.4 18:05:07 INFO - docopt == 0.6.1 18:05:07 INFO - functools32 == 3.2.3-2 18:05:07 INFO - jsonschema == 2.5.1 18:05:07 INFO - manifestparser == 1.1 18:05:07 INFO - mozInstall == 1.12 18:05:07 INFO - mozcrash == 1.0 18:05:07 INFO - mozdebug == 0.1 18:05:07 INFO - mozdevice == 0.49 18:05:07 INFO - mozfile == 1.2 18:05:07 INFO - mozhttpd == 0.7 18:05:07 INFO - mozinfo == 0.9 18:05:07 INFO - mozleak == 0.1 18:05:07 INFO - mozlog == 3.4 18:05:07 INFO - moznetwork == 0.27 18:05:07 INFO - mozprocess == 0.25 18:05:07 INFO - mozprofile == 0.28 18:05:07 INFO - mozrunner == 6.13 18:05:07 INFO - mozscreenshot == 0.1 18:05:07 INFO - mozsystemmonitor == 0.3 18:05:07 INFO - moztest == 0.8 18:05:07 INFO - mozversion == 1.4 18:05:07 INFO - psutil == 3.1.1 18:05:07 INFO - requests == 1.2.3 18:05:07 INFO - wsgiref == 0.1.2 18:05:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:07 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')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:07 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')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:07 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:05:07 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 18:05:07 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 18:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:07 INFO - 'HOME': '/Users/cltbld', 18:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PAGER': '/bin/cat', 18:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:05:07 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - 'XPC_FLAGS': '0x0', 18:05:07 INFO - 'XPC_SERVICE_NAME': '0', 18:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 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)) 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:07 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')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:07 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')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:07 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 0x103df2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a2cd26b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, '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.nnPa6O8DXf/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.5zfnld5aCv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 18:05:07 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 18:05:07 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 18:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:07 INFO - 'HOME': '/Users/cltbld', 18:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PAGER': '/bin/cat', 18:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:05:07 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - 'XPC_FLAGS': '0x0', 18:05:07 INFO - 'XPC_SERVICE_NAME': '0', 18:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Running post-action listener: _resource_record_post_action 18:05:07 INFO - Running post-action listener: _start_resource_monitoring 18:05:07 INFO - Starting resource monitoring. 18:05:07 INFO - [mozharness: 2017-04-26 01:05:07.876108Z] Finished create-virtualenv step (success) 18:05:07 INFO - [mozharness: 2017-04-26 01:05:07.877070Z] Running install step. 18:05:07 INFO - Running pre-action listener: _resource_record_pre_action 18:05:07 INFO - Running main action method: install 18:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:08 INFO - Reading from file tmpfile_stdout 18:05:08 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:08 INFO - Reading from file tmpfile_stdout 18:05:08 INFO - Output received: 18:05:08 INFO - Usage: mozinstall [options] installer 18:05:08 INFO - Options: 18:05:08 INFO - -h, --help show this help message and exit 18:05:08 INFO - -d DEST, --destination=DEST 18:05:08 INFO - Directory to install application into. [default: 18:05:08 INFO - "/builds/slave/test"] 18:05:08 INFO - --app=APP Application being installed. [default: firefox] 18:05:08 INFO - mkdir: /builds/slave/test/build/application 18:05:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 18:05:23 INFO - Reading from file tmpfile_stdout 18:05:23 INFO - Output received: 18:05:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:05:23 INFO - Running post-action listener: _resource_record_post_action 18:05:23 INFO - [mozharness: 2017-04-26 01:05:23.494850Z] Finished install step (success) 18:05:23 INFO - [mozharness: 2017-04-26 01:05:23.495013Z] Running run-tests step. 18:05:23 INFO - Running pre-action listener: _resource_record_pre_action 18:05:23 INFO - Running main action method: run_tests 18:05:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:23 INFO - 2017-04-25 18:05:23.508 screenresolution[1627:7318] starting screenresolution argv=screenresolution get 18:05:23 INFO - 2017-04-25 18:05:23.511 screenresolution[1627:7318] Display 0: 1600x1200x32@60 18:05:23 INFO - 2017-04-25 18:05:23.517 screenresolution[1628:7322] starting screenresolution argv=screenresolution list 18:05:23 INFO - Available Modes on Display 0 18:05:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:23 INFO - Intel Iris: 18:05:23 INFO - Chipset Model: Intel Iris 18:05:23 INFO - Type: GPU 18:05:23 INFO - Bus: Built-In 18:05:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:23 INFO - Vendor: Intel (0x8086) 18:05:23 INFO - Device ID: 0x0a2e 18:05:23 INFO - Revision ID: 0x0009 18:05:23 INFO - Displays: 18:05:23 INFO - Display: 18:05:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:23 INFO - Display Serial Number: 1600x1200 60 18:05:23 INFO - Main Display: Yes 18:05:23 INFO - Mirror: Off 18:05:23 INFO - Online: Yes 18:05:23 INFO - Rotation: Supported 18:05:23 INFO - Return code: 0 18:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:23 INFO - Python 2.7.3 18:05:23 INFO - Return code: 0 18:05:23 INFO - grabbing minidump binary from tooltool 18:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:23 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 0x103bd0d30>, '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 0x103af7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bc5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:23 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 18:05:23 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 18:05:23 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 18:05:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:23 INFO - Return code: 0 18:05:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:23 INFO - ENV: RUST_BACKTRACE is now 1 18:05:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:23 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/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 18:05:23 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/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 18:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnPa6O8DXf/Render', 18:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:23 INFO - 'HOME': '/Users/cltbld', 18:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:23 INFO - 'LOGNAME': 'cltbld', 18:05:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:23 INFO - 'MOZ_NO_REMOTE': '1', 18:05:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:23 INFO - 'NO_EM_RESTART': '1', 18:05:23 INFO - 'PAGER': '/bin/cat', 18:05:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:23 INFO - 'PWD': '/builds/slave/test', 18:05:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:23 INFO - 'RUST_BACKTRACE': '1', 18:05:23 INFO - 'SHELL': '/bin/bash', 18:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zfnld5aCv/Listeners', 18:05:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:23 INFO - 'USER': 'cltbld', 18:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:23 INFO - 'XPC_FLAGS': '0x0', 18:05:23 INFO - 'XPC_SERVICE_NAME': '0', 18:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:23 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/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 18:05:24 INFO - mozversion application_buildid: 20170425163425 18:05:24 INFO - mozversion application_changeset: 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 18:05:24 INFO - mozversion application_display_name: Firefox 18:05:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:24 INFO - mozversion application_name: Firefox 18:05:24 INFO - mozversion application_remotingname: firefox 18:05:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:05:24 INFO - mozversion application_vendor: Mozilla 18:05:24 INFO - mozversion application_version: 54.0 18:05:24 INFO - mozversion platform_buildid: 20170425163425 18:05:24 INFO - mozversion platform_changeset: 68fe3cab6553b08d23a2e6dbbfb63f1a0a54fff6 18:05:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:05:24 INFO - mozversion platform_version: 54.0 18:05:24 INFO - using testdate: 1493168724 18:05:24 INFO - actual date: 1493168724 18:05:24 INFO - starting webserver on 'localhost:49241' 18:05:24 INFO - SUITE-START | Running 2 tests 18:05:24 INFO - TEST-START | dromaeo_css 18:05:24 INFO - operating with platform_type : mac_ 18:05:24 INFO - Initialising browser for dromaeo_css test... 18:05:24 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpN5skVc/profile http://localhost:49241/getInfo.html) 18:05:26 INFO - PROCESS | 1638 | 2017-04-25 18:05:26.785 plugin-container[1641:7489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 18:05:26 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:26 INFO - PROCESS | 1638 | 2017-04-25 18:05:26.786 plugin-container[1641:7489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 18:05:26 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:26 INFO - PROCESS | 1638 | 2017-04-25 18:05:26.786 plugin-container[1641:7489] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:05:26 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 18:05:26 INFO - PROCESS | 1638 | colorDepth:24 18:05:26 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 18:05:26 INFO - PROCESS | 1638 | __metrics 18:05:26 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 18:05:27 INFO - TEST-INFO | 1638: exit 0 18:05:27 INFO - Browser initialized. 18:05:27 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:27 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpN5skVc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:29 INFO - PROCESS | 1642 | 2017-04-25 18:05:29.693 plugin-container[1643:7615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 18:05:29 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:29 INFO - PROCESS | 1642 | 2017-04-25 18:05:29.694 plugin-container[1643:7615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 18:05:29 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:29 INFO - PROCESS | 1642 | 2017-04-25 18:05:29.694 plugin-container[1643:7615] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:05:39 INFO - PROCESS | 1642 | RSS: Main: 174526464 18:05:39 INFO - PROCESS | 1642 | 18:08:42 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 18:08:44 INFO - PROCESS | 1642 | RSS: Main: 189513728 18:08:44 INFO - PROCESS | 1642 | 18:11:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:11:47 INFO - PROCESS | 1642 | RSS: Main: 202588160 18:11:47 INFO - PROCESS | 1642 | 18:14:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:14:49 INFO - PROCESS | 1642 | RSS: Main: 204136448 18:14:49 INFO - PROCESS | 1642 | 18:17:51 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:17:52 INFO - PROCESS | 1642 | RSS: Main: 207310848 18:17:52 INFO - PROCESS | 1642 | 18:20:55 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:20:55 INFO - PROCESS | 1642 | RSS: Main: 209715200 18:20:55 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 18:23:58 INFO - PROCESS | 1642 | RSS: Main: 208613376 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | __start_tp_report 18:23:58 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 18:23:58 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 18:23:58 INFO - PROCESS | 1642 | |i|pagename|runs| 18:23:58 INFO - PROCESS | 1642 | |0;dojo.html;2818.7604053655436;3661.2677464507087;3682.1715114099316;3687.11509237783;3709.7588656737316;4994.6753461025055;5854.88290234195;5860.50185734212;6266.436020758131;6269.090981807635;6190.071489276599;6209.782657606985;6228.84427889303;6267.185265915436;6272.996320588707;5581.972090139554;5641.774329026834;5645.322561292651;5647.915281270784;5711.343195532505;5649.971750141243;5674.205611214434;5696.458836410535;5698.943010569883;5747.2241247431475;4701.459332176809;5404.297441332621;5482.424345443731;5683.573731970098;5689.459501347365;5590.91613625796;5596.2165296858475;5606.24315717377;5614.494695477406;5636.126400407943;5534.252875861747;5616.66300022;5639;5650.915236271459;5730.914036289459;6289.685515724195;6312;6331.240251169843;6332.176817013758;6345.33373995733;6368.808935731936;6395.712192951329;6647.824529278316;6671.099401580773;6721.63031033293;5421.701806400646;6607.900881486781;6783.152105986752;6803.353681400259;6804.76183333581;6307.747690092437;6339.809805705836;6365.077063825765;6372.745090196387;6464.41820236181;5688.402717714663;6620.437262832616;6677.265500794909;6922.134733158356;6941.514094013472;5474.890502190014;6442.5812322199845;6463.418292353711;6478.514111441661;6484.513661475408;6714.194296684429;6715.2613212546585;6745.190577130776;6748.392644661906;6768.593884366954;6633.336666333328;6645.368689974444;6645.468362530986;6664.100346453167;6666.933330666729;5351.545118664879;6042.939570604326;6386.744530218833;6469.838254043687;6547.607143571306;4768.833090841804;5189.922151167735;5195.506426889439;5328.254044433782;5349.919751203735;4715.151272770866;4716.127516409407;4720.811167553328;4729;4745.217039188564;6140.657627863787;6234.604603754342;6371.267304259976;6388.4569811566535;6397.232332120082;6599.967000165065;6608.900866487006;6682.331766823376;6690.59856408617;6719.899201511981;6319.715612797435;6331.2085989251345;6342.270638876496;6344.524160687967;6348.174737284124;5931.406859314034;5945.227120474311;5946.643201407929;5954.851128721816;5956.7319470623925;6681.031250468654;6723.63020033893;6779.661016949034;6913.101296831581;6987.441004719712;6745.5952642841585;6755.189377274758;6778.423833974168;6782.966085169543;6783.694733736895;6859.9314006859295;6940.514164008471;6978;7005.754798582026;7023.789286321418;6395.264544577247;6435.678216089083;6522.869542609216;6530.151080359523;6686.297938716365;6733.922572388395;6738.528303018742;6743.123393958754;6758.324167583399;6762.898556521558;4855.611551075976;6495.935040649533;6523.282438931618;6526.967365163144;6557;5639.210510528447;6279.748810047547;6335.429811316819;6543;6564.573302735307;5736.942630573641;6116.1836049259855;6345.96827015862;6346.492280617447;6418.679066046772;6543.934560654333;6550.705218265094;6569.211694596679;6579.243387010555;6589.6705164740615;6145.955187618027;6172.105044768573;6340.9048864268;6405.679716014088;6412.294647588668 18:23:58 INFO - PROCESS | 1642 | |1;ext.html;774.1793698679401;823.5429336718123;854.4531499840103;923.9815203695922;935.5930170375883;37845;38943;39463.605363946386;40453;42328.99999999996;40877.7956110219;40930.79534602323;41326.58673413265;41326.793366033125;41389;8266.751997440071;8396.790080247982;11411.31532108072;11709.824352634718;11908.40457977102;9503.429794212334;11630.941845290761;11633.127515436376;11654.30074195551;11659.766804663901;11593.36236506996;11641.01051410631;11663.941680291588;11673.883261167408;11680.299182049104;9021.684241051564;9620.182284505825;11545.249558778736;11600.187986840925;11623.476943537518;11389.088872890148;11401.600943967005;11409;11409.54361825526;11460.598879039277;28350.157239779553;35382;35799.64200358015;35878.46182307268;35966;3880.417937309398;3904.403571624866;3940.1922605866016;3950.743201691881;4022.0950286185375;26259.606105908428;26466.73533264681;26498.337541561614;27670.893164273406;27747.58378624302;3210.598675165604;3283.507473878937;3291.883650476193;3312.5362449257123;3417.538632284635;349036.2548187268;350851;351222;351242.99999999744;351535;43957.56042439599;44493.332600011025;44549.331760023626;44623.330650040276;44772.552274477486;46711;46951.5304846954;47060.7646961763;47061.82345441391;47204.7639761799;13663.111897726814;14049.367778449994;14103.85896141046;14114.85885141156;14147.22190279524;11904.583339583072;12111.091668124925;12124.454399552042;12129.332886691316;12133.5146594137;12215.328156951453;12232.81650775239;12266.71199524055;12268.938655306667;12366.814497782541;5850.268716410449;5876.647401155944;5901.7639294427745;5910.320313163955;5922.230110085661;1036.461040259076;1108.1965574958251;1125.7298248420484;1151.3667482884548;1167.2996202278734;27682.446351072867;27830.72169278322;28195.859020704764;28217.71782282192;28308.858455708003;1072.769354588767;1258.622413276032;1278.2202856257675;1291.3930452687223;1292.2440372382262;13004.674883127998;13950.651233719238;14242.572722818331;14412.351444184827;14671.706565868622;385915;421021;461765;463043;465196;59496;59550;59713.701431493435;59782.402175978554;59893;1294.1975974895436;1330.1420583723443;1492.9477468288558;1506.6835964447368;1524.4283393727353;30409.087727368213;31909.521357179197;31954.36091278208;31980.840095799373;32585.674143258264;44264;44376.556234437245;44414.77792611016;44455.77772111119;44471;439323;440397;441222;441225;444031;26569.60145597856;31757;33244;33251.50122748112;33323.16692082717;44977.32534011927;46340.30489542592;47181;47297;47686.04627907353;31005.534916976714;31509.684903150675;31641;32065;32710.672893271716;25190.748092519574;31459.213519662193;31459.685403145675;31568.210794730316;31648.841755791073;36395.45406818953;36720.449193262655;37259;37586.43620345752;37641.81179094087;30838.845805770827;31128.377432451678;31234.21914452157;31965.840170798998;32176.839115804272;22023.77976220217;25130.62304065477;25309.240722778348;25396.619050713885;25730.742692572836 18:23:58 INFO - PROCESS | 1642 | |2;jquery.html;419.20560537780887;424.940508328834;434.67182277380573;440.4143809975874;441.9977900110499;17965.28138874445;18070;20779.85710785906;21195.47011324718;23103;22627.868606569646;23226.65160022601;23645.5270894582;23723.644145337792;24147.517049658996;6794.184697836266;6967.059446974644;7131.572105673677;7192.640367981606;7198.8200294992785;5715.885682286352;7103.680334384965;7127.679254433563;7141.107361579802;7153.964230178842;6769.796906092825;7061.964690176567;7064.964675176617;7076.469264805134;7120.394766444832;5479.890402191933;6907.378335949788;7003.089598352232;7010.684519196649;7036.577805331696;6767.560108592926;6851.897221541631;6852.897206541906;6871.725130994754;6885.827854303632;27496.17511474659;27578.724212758018;28096.15711528637;28194.436111277864;29158.70841291581;3037.741791947665;3100.953485697716;3142.104500217427;3145.685431456859;3147.2603938074535;16994.23525941323;17166.742498862528;17183.3985810496;17227.22477488516;17360.565985850328;2137.8931053447263;2176.1077958037285;2191.583599116179;2203.4711669199437;2262.1290803040783;109519;142248;142490;144799;145129.99999999895;22774.43063923382;22948.541029179323;23126.768732312798;23415.648765268535;23524;36697.26605467876;36887.81556092256;37269.068273293386;37790;37857;11107.111431085494;11250.481185040078;11457.608722464722;11458.484368203452;11864.99147572466;9243.491607961627;9294.628214871329;9308.720738377859;9445.622175112918;9490.525473726286;8464.44981076228;9042.63829446828;11122.55509779616;11256.26834255787;11369.602063927723;3894.6689531389584;4270.978645106817;4284.614384705392;4669.789859456333;4694.906101877943;1051.8369652703843;1063.3726101600093;1079.8542196803487;1095.956161753537;1141;23575.528489430115;23622.29133126009;23713.644295335584;23855.64216536753;23931.162409315937;949.8100379924043;1109.378747901178;1114.8717897441898;1157.5948418053613;1161.6050542815483;12147.907500510597;12403.813942790865;12519.436625351698;12553.435095420546;12676.302803345905;663616;665629;702109;716373;739598;124064;137883;143454.28272858777;144743;147104;1265.2724683307135;1277.9297138657464;1288.7486940046788;1299.616613099157;1304.4912484131007;18108.90945545264;19792;20915.058822353334;21129;21171.8941405292;44148.7792561048;55250.44749552453;60617.48456288628;62150.37849621446;63389.36610633835;81755.59122204351;82326;84662;84666;84859.57570212109;8224.876626850722;11884.465199066235;12192.841680040381;12305.56930507428;12835.486580536674;13961.930190348983;14023.859761402255;14459.98780085384;14466.421343146156;14574.85425145735;11729.530818767495;11787.11596630239;11998.520059197534;12132.878671213175;12242.143049986771;7790.766277011472;11205.88794112081;11250.83123753128;11422.885771142515;11732.178747487595;10028.849567256642;11960.76078478443;12004.519819207135;12270.938645306716;12277.447514861675;12631.305278209788;12662;12817.1668841525;12994.220346779224;13048.673783155496;10948.890511095105;11241.932016458424;11607.593734219261;11818.527258909891;12041.157119001937 18:23:58 INFO - PROCESS | 1642 | |3;mootools.html;1632.2817960097564;1641.5403686967645;1658.56048147241;1660.3275673352293;1667.533090734594;11056.557737690482;11335.943320283408;11610.825837612421;11672.533098676053;11692.29846209228;11502.137339699515;11636.301821890693;11641;11670.883291167085;11673.357965311905;2418.6492958521003;2462.285937078254;2472.3077538289217;2473.4558397152596;2473.468204336067;2089.4538041849073;2407.759224077587;2408.3015925381706;2466.124525793344;2473.703155621328;2394.521095780842;2441.0967941861445;2451.6935383077116;2470.6170543565686;2472.159465781634;2351.588472017407;2396.916107936231;2414.396400899775;2426.8472475574135;2428.138011006094;2024.3117340104288;2394.150076722773;2394.616861302183;2418.806495480357;2421.7093948726088;10350.53422595985;11074.723131921686;11485.368304743235;11876.584319548774;11903.523859045628;5923.555733320019;6012.398760123996;6099.359567245419;6111.480524155452;6115.938840611581;3533.628968958248;3693.0582701411236;3709.2766910452474;3742.5321834770652;3762.1347090169143;1236.3509157692283;1239.1264158768047;1271.6185144456626;1291.9935400322938;1295.6372215779597;14111.576652700436;14388;15266;16167.676646467005;16334.591635209095;4737.8341758038305;4750.667453278306;4767.237242041257;4888.388951381077;4893.755312234374;11928.940355298168;12040.81938770919;12092.274463532216;12112.69718257033;12117.818232726517;5971.731272092767;5984.700764961822;5988.610740301867;5990.700464976734;5997.670128142907;5375.865603359869;5403.756830942617;5411.891762164813;5446.101393270145;5462.836114916479;3190.744740420761;3994.021464741157;4024.054347228399;4082.897927551835;4121;1040.843873418993;1043.0977204717865;1045.560864436923;1054.4200689620677;1056.0231785598291;944.7165850244899;962.764122789921;976.1458723616835;994.2642444591095;1000.9840856452627;6631.502637302222;6669.866602667919;6691.6951194516605;6766.695498702472;6794.946783248604;971.4511301114848;1027.6095083868083;1034.2036631793476;1039.184240371305;1059.2796898109204;3483.042163405084;4067.8576249831112;4362;4365.628921541641;4418.889527761831;13246.20522768637;14372;14564.563063108124;14565.271736413137;14567.562973110824;14058.71882562343;14292.571222863331;14360.856391435951;14436.278186090654;14465.204413757134;8960.19358257773;9141.177294043568;9822.410655360558;10384.740381490523;10585;6480.351964803482;6959.652017399211;6978.302169782981;7072.858542829012;7246.855062898818;12413.93793031029;12867.099303049072;13039.804402933774;13049.739005220032;13114.213147211198;12239.632811015685;13252.00609954238;13380.933095334462;13401.396937138048;13420.127691700009;9442.858357124785;9680.709578712649;9709.70870873875;9732.270079743907;9746.171575416169;10209.38743675382;10617.256792024484;10680.57277708914;10702.304350217211;10789.784204316027;9003.459792412476;9130.863037054582;9188.127127922837;9204.493752843659;9408.01215872351;8913.153250441197;8961.955190224007;8973.147550982647;9040.547972601475;9067.229285510806;8223.301019413417;8320.625571849161;8378.664853405795;8404.70583529598;8420.789480263042;5914.4381283777975;5959.880802384015;5994.430529099728;6090.908636370369;6205.069239614094;6091;6212.565120441525;6232.09634602971;6297.905531417124;6310.5267104968225 18:23:58 INFO - PROCESS | 1642 | |4;prototype.html;329.79387882573394;379.9323899841444;382.70149283558817;393.15864050931003;399.303215888275;5342.592361301922;5406.621536492442;5507.870886468274;5514.904336352186;5526;4357.651387888969;4395.68129561132;4959.900801983958;4983.651144419883;5339.22581225722;4009.6591789697836;4371.715838470505;4427.645788336941;4459.26422140348;4513.3455648930885;3589.9282014359696;4445.888203103532;4478.910421791562;4496.325551167335;4498.392716983214;4496.123255965088;4506.504284528699;4507.977460112695;4518.322251662261;4518.508892065611;3866.4780254665543;4151.750894946313;4300.827966881321;4480.507144214133;4554.72467709042;4076.5923407659293;4099.647116451564;4127.711060225785;4128.814203360856;4132.884121287266;5311;5341.252224688546;5347.946520534783;5385.8259848617245;5400.081986062379;4796.664233503656;4838.709677419366;4861.492388320439;5157.252198431206;5235.439900867409;4928.383952005999;5200.375954885438;5511.5039646432015;5565.554755619541;5763.665715385276;4168.937465938014;4461.620762235214;4517.170545928871;4653.7905794239;4672.065586882637;4951.9752401237765;4977.278294647292;4992.525710057539;6088.9391106088815;6152.138700581922;4432.113577284556;4709.434867815884;4726.692764970266;4732.976335118371;4786;4599.471060827981;4816.614670826326;4900.705957642553;4904.2643603459055;4914.459409464956;4067.6814215896657;4487.4069705254815;4564.790330562404;4862.878428039327;5169.612279079084;5397.136458166753;5414.675119492843;5698.262030080865;5748.815450128925;5754.568407369464;1390.9721805563831;1407.7114191590904;1473.130852796855;1633.2405431474422;1645.5474744445373;1030.4847576211894;1040.5057597641212;1087.3041253597703;1161.7095726068483;1229.416027386984;4996.675216110941;5210.925553923204;5398.218587865995;5407.080796264644;5438.428964958702;5236.947630523646;5286.841394758163;5432.619716619799;6031.390806549951;6111.938880611137;5059.569936555434;5344.732763361816;5364.275822763877;5378.515933565998;5474.863128421742;2735.480258750831;2756.043209321364;2924.4735947529225;2946.4401763664837;3104.487759519699;5033.672811267329;5407.053765591006;5619.775208991595;5785.17766903424;6232.637153992224;5458.962797068623;5498.862528436821;6013.737115205856;6089.482393996472;6108.670774427947;5089.923651145162;5255.5532779713385;6083.9391606084255;6124.938750612437;6321.302038307642;5591.608587398843;5932.762689492372;6002.8294482575475;6007.729652165575;6034.09488576717;5044;5072.644914855925;5329.228801023523;5565.686592890543;5737.942620573908;6225.346338634378;6227.252729672468;6246.250449946036;6250.9998400255745;6273.404026617464;4261.31818908973;4567.725936443824;4749;4775.71345719258;4804.231322988305;4834.758262086812;5949.762009519571;5956.910646340221;6003.199040287809;6079.452849243589;4825.6139508839915;5431.8098866539485;5706.176861502886;5707.143928410772;5870.853228669147;5150.330457040561;5466.835994920159;5652.484644333396;5777.782218980084;5818.301803783573;5117.641765076409;5399.83800485986;5632.464915832989;5661.915071274017;5686.459786320293;5199.4020687621405;5230.320058392384;5849.5612829037145;5865.178874957424;5903.793367232126;5081.568066714371;5607.084316155658;5617.129344951539;5628.324601047737;5638.802641907513 18:23:58 INFO - PROCESS | 1642 | |5;yui.html;743.9293267139619;758.6510205305563;804.2841870735047;863.4258218284839;868.7958329792499;12692.682682932931;16925.238364273606;17106.82893171068;17139.228734706954;17183.570410739736;15058.34358220595;15142;15148.318325675344;15163.848361516382;15192.772108418383;980.0248752491264;997.2371136080868;1004.9246306527003;1005.4269066632023;1046.1735229168946;1024.902634249745;1035.7824856780048;1039.6049501189536;1042.4422933730455;1049.176396528725;1084.0514549768952;1084.6203828659984;1085.6091806949491;1087.2443651662097;1087.456271864068;1065.4992153687792;1080.8324709670014;1081.3187691754224;1083.3391631105019;1084.2247792828166;788.282662776876;800.1878093734864;802.5104686141449;803.0203152154362;803.337246771416;16622.50132496027;17136.31454741809;17239.569010774707;17384;17815.198316075806;14817.407303707947;15537.689246215126;15603.375864965388;15740.134292613902;15842.207889605474;11885.881141188564;11918.582849600223;12087.576934807239;12185.878141218564;12396.318202498858;3529.2588556403184;3979.1047014421815;3979.9602003980167;3993.0815912340036;4010.418489319062;90685.54657226738;113152.4342378291;117647.4117629415;121018.57962840782;122389.99999999911;11739.88260117405;11770.411479426166;11894.16740828151;11953.103517236243;11986.64040078799;21513.892430537748;26980.32549186286;27113.322166945985;27321.86339068292;27341.863290683817;7496.325330720261;8186.713465028814;8444.451110677786;8596.699115531052;8597.871031934526;4608.493065762764;4621.838235661803;4670.95329046712;4705.270683044134;4859.003904199631;3381.3406385754542;3438.157651375379;3468.7051600613727;3484.8257587120543;3487.5814902211896;605.8424809549549;658.601546064624;687.8246047257917;688.0470548290654;688.7072993977539;569.4908492494839;594.669958173218;595.3619225930432;605.5639939243738;624.1947887225429;10281.74295642615;10955.616553420592;11025.889741102646;11091.667249982513;11171.38557379352;694.2675477371321;726.7928640337478;733.8275505256261;734.5225603357753;736.2122528894032;4952.380952380952;5347.406507843692;5430.266913966564;5637.3235211774845;5653.802116925888;109346.45326773316;110223.44888275507;111075;113487.43256283666;113736;72110.63944680244;91035.90338434787;92636;95535.52232238794;97253.0274697244;83676.16323836806;84684;86739.13260867436;86906;86924;11181.832272515756;11272;11353.659390218307;11359.886401135884;11525.308481491138;39923;41015.794921025205;46994.29508557306;53800.92398152094;54984.90030199454;56358.436415636956;69110;70582.64708676423;73844.2615573859;74785.6260718693;12887.033472489788;13382.531611393555;13465.192088474723;13518.121322114031;13956.790648140082;15399.923000385375;15456;15850.048997059752;15927.601809954844;16486.505404837873;14116.647083822987;14286.714265714836;14458.20479873575;14505.274736262934;14974.625634359229;13134.277614731282;13756.608498628628;13881.097728647606;13892.16647001183;13946;11274.830877537008;13767.242801646005;13995.440182392998;14159.938004649899;14167.787483187967;9761.511924403609;9923.057309555581;10173.796524069625;10261.640842570474;10388.740281493023;9621.114943274508;9719.951400242953;11145.164112691742;11665.88334116648;11689.766204675689 18:23:58 INFO - PROCESS | 1642 | __end_tp_report 18:23:58 INFO - PROCESS | 1642 | __start_cc_report 18:23:58 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,394 18:23:58 INFO - PROCESS | 1642 | __end_cc_report 18:23:58 INFO - PROCESS | 1642 | __startTimestamp1493169838875__endTimestamp 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | ------- Summary: start ------- 18:23:58 INFO - PROCESS | 1642 | Number of tests: 6 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:6126.21 Median:6345.97 stddev:728.32 (11.5%) stddev-sans-first:682.78 18:23:58 INFO - PROCESS | 1642 | Values: 2818.8 3661.3 3682.2 3687.1 3709.8 4994.7 5854.9 5860.5 6266.4 6269.1 6190.1 6209.8 6228.8 6267.2 6273.0 5582.0 5641.8 5645.3 5647.9 5711.3 5650.0 5674.2 5696.5 5698.9 5747.2 4701.5 5404.3 5482.4 5683.6 5689.5 5590.9 5596.2 5606.2 5614.5 5636.1 5534.3 5616.7 5639.0 5650.9 5730.9 6289.7 6312.0 6331.2 6332.2 6345.3 6368.8 6395.7 6647.8 6671.1 6721.6 5421.7 6607.9 6783.2 6803.4 6804.8 6307.7 6339.8 6365.1 6372.7 6464.4 5688.4 6620.4 6677.3 6922.1 6941.5 5474.9 6442.6 6463.4 6478.5 6484.5 6714.2 6715.3 6745.2 6748.4 6768.6 6633.3 6645.4 6645.5 6664.1 6666.9 5351.5 6042.9 6386.7 6469.8 6547.6 4768.8 5189.9 5195.5 5328.3 5349.9 4715.2 4716.1 4720.8 4729.0 4745.2 6140.7 6234.6 6371.3 6388.5 6397.2 6600.0 6608.9 6682.3 6690.6 6719.9 6319.7 6331.2 6342.3 6344.5 6348.2 5931.4 5945.2 5946.6 5954.9 5956.7 6681.0 6723.6 6779.7 6913.1 6987.4 6745.6 6755.2 6778.4 6783.0 6783.7 6859.9 6940.5 6978.0 7005.8 7023.8 6395.3 6435.7 6522.9 6530.2 6686.3 6733.9 6738.5 6743.1 6758.3 6762.9 4855.6 6495.9 6523.3 6527.0 6557.0 5639.2 6279.7 6335.4 6543.0 6564.6 5736.9 6116.2 6346.0 6346.5 6418.7 6543.9 6550.7 6569.2 6579.2 6589.7 6146.0 6172.1 6340.9 6405.7 6412.3 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:55226.50 Median:17693.72 stddev:109362.68 (618.1%) stddev-sans-first:109593.05 18:23:58 INFO - PROCESS | 1642 | Values: 774.2 823.5 854.5 924.0 935.6 37845.0 38943.0 39463.6 40453.0 42329.0 40877.8 40930.8 41326.6 41326.8 41389.0 8266.8 8396.8 11411.3 11709.8 11908.4 9503.4 11630.9 11633.1 11654.3 11659.8 11593.4 11641.0 11663.9 11673.9 11680.3 9021.7 9620.2 11545.2 11600.2 11623.5 11389.1 11401.6 11409.0 11409.5 11460.6 28350.2 35382.0 35799.6 35878.5 35966.0 3880.4 3904.4 3940.2 3950.7 4022.1 26259.6 26466.7 26498.3 27670.9 27747.6 3210.6 3283.5 3291.9 3312.5 3417.5 349036.3 350851.0 351222.0 351243.0 351535.0 43957.6 44493.3 44549.3 44623.3 44772.6 46711.0 46951.5 47060.8 47061.8 47204.8 13663.1 14049.4 14103.9 14114.9 14147.2 11904.6 12111.1 12124.5 12129.3 12133.5 12215.3 12232.8 12266.7 12268.9 12366.8 5850.3 5876.6 5901.8 5910.3 5922.2 1036.5 1108.2 1125.7 1151.4 1167.3 27682.4 27830.7 28195.9 28217.7 28308.9 1072.8 1258.6 1278.2 1291.4 1292.2 13004.7 13950.7 14242.6 14412.4 14671.7 385915.0 421021.0 461765.0 463043.0 465196.0 59496.0 59550.0 59713.7 59782.4 59893.0 1294.2 1330.1 1492.9 1506.7 1524.4 30409.1 31909.5 31954.4 31980.8 32585.7 44264.0 44376.6 44414.8 44455.8 44471.0 439323.0 440397.0 441222.0 441225.0 444031.0 26569.6 31757.0 33244.0 33251.5 33323.2 44977.3 46340.3 47181.0 47297.0 47686.0 31005.5 31509.7 31641.0 32065.0 32710.7 25190.7 31459.2 31459.7 31568.2 31648.8 36395.5 36720.4 37259.0 37586.4 37641.8 30838.8 31128.4 31234.2 31965.8 32176.8 22023.8 25130.6 25309.2 25396.6 25730.7 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:41221.43 Median:22861.49 stddev:115988.49 (507.4%) stddev-sans-first:116273.40 18:23:58 INFO - PROCESS | 1642 | Values: 419.2 424.9 434.7 440.4 442.0 17965.3 18070.0 20779.9 21195.5 23103.0 22627.9 23226.7 23645.5 23723.6 24147.5 6794.2 6967.1 7131.6 7192.6 7198.8 5715.9 7103.7 7127.7 7141.1 7154.0 6769.8 7062.0 7065.0 7076.5 7120.4 5479.9 6907.4 7003.1 7010.7 7036.6 6767.6 6851.9 6852.9 6871.7 6885.8 27496.2 27578.7 28096.2 28194.4 29158.7 3037.7 3101.0 3142.1 3145.7 3147.3 16994.2 17166.7 17183.4 17227.2 17360.6 2137.9 2176.1 2191.6 2203.5 2262.1 109519.0 142248.0 142490.0 144799.0 145130.0 22774.4 22948.5 23126.8 23415.6 23524.0 36697.3 36887.8 37269.1 37790.0 37857.0 11107.1 11250.5 11457.6 11458.5 11865.0 9243.5 9294.6 9308.7 9445.6 9490.5 8464.4 9042.6 11122.6 11256.3 11369.6 3894.7 4271.0 4284.6 4669.8 4694.9 1051.8 1063.4 1079.9 1096.0 1141.0 23575.5 23622.3 23713.6 23855.6 23931.2 949.8 1109.4 1114.9 1157.6 1161.6 12147.9 12403.8 12519.4 12553.4 12676.3 663616.0 665629.0 702109.0 716373.0 739598.0 124064.0 137883.0 143454.3 144743.0 147104.0 1265.3 1277.9 1288.7 1299.6 1304.5 18108.9 19792.0 20915.1 21129.0 21171.9 44148.8 55250.4 60617.5 62150.4 63389.4 81755.6 82326.0 84662.0 84666.0 84859.6 8224.9 11884.5 12192.8 12305.6 12835.5 13961.9 14023.9 14460.0 14466.4 14574.9 11729.5 11787.1 11998.5 12132.9 12242.1 7790.8 11205.9 11250.8 11422.9 11732.2 10028.8 11960.8 12004.5 12270.9 12277.4 12631.3 12662.0 12817.2 12994.2 13048.7 10948.9 11241.9 11607.6 11818.5 12041.2 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6967.08 Median:2468.37 stddev:4383.94 (177.6%) stddev-sans-first:4377.92 18:23:58 INFO - PROCESS | 1642 | Values: 1632.3 1641.5 1658.6 1660.3 1667.5 11056.6 11335.9 11610.8 11672.5 11692.3 11502.1 11636.3 11641.0 11670.9 11673.4 2418.6 2462.3 2472.3 2473.5 2473.5 2089.5 2407.8 2408.3 2466.1 2473.7 2394.5 2441.1 2451.7 2470.6 2472.2 2351.6 2396.9 2414.4 2426.8 2428.1 2024.3 2394.2 2394.6 2418.8 2421.7 10350.5 11074.7 11485.4 11876.6 11903.5 5923.6 6012.4 6099.4 6111.5 6115.9 3533.6 3693.1 3709.3 3742.5 3762.1 1236.4 1239.1 1271.6 1292.0 1295.6 14111.6 14388.0 15266.0 16167.7 16334.6 4737.8 4750.7 4767.2 4888.4 4893.8 11928.9 12040.8 12092.3 12112.7 12117.8 5971.7 5984.7 5988.6 5990.7 5997.7 5375.9 5403.8 5411.9 5446.1 5462.8 3190.7 3994.0 4024.1 4082.9 4121.0 1040.8 1043.1 1045.6 1054.4 1056.0 944.7 962.8 976.1 994.3 1001.0 6631.5 6669.9 6691.7 6766.7 6794.9 971.5 1027.6 1034.2 1039.2 1059.3 3483.0 4067.9 4362.0 4365.6 4418.9 13246.2 14372.0 14564.6 14565.3 14567.6 14058.7 14292.6 14360.9 14436.3 14465.2 8960.2 9141.2 9822.4 10384.7 10585.0 6480.4 6959.7 6978.3 7072.9 7246.9 12413.9 12867.1 13039.8 13049.7 13114.2 12239.6 13252.0 13380.9 13401.4 13420.1 9442.9 9680.7 9709.7 9732.3 9746.2 10209.4 10617.3 10680.6 10702.3 10789.8 9003.5 9130.9 9188.1 9204.5 9408.0 8913.2 8962.0 8973.1 9040.5 9067.2 8223.3 8320.6 8378.7 8404.7 8420.8 5914.4 5959.9 5994.4 6090.9 6205.1 6091.0 6212.6 6232.1 6297.9 6310.5 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4782.57 Median:5153.79 stddev:1346.87 (26.1%) stddev-sans-first:1308.52 18:23:58 INFO - PROCESS | 1642 | Values: 329.8 379.9 382.7 393.2 399.3 5342.6 5406.6 5507.9 5514.9 5526.0 4357.7 4395.7 4959.9 4983.7 5339.2 4009.7 4371.7 4427.6 4459.3 4513.3 3589.9 4445.9 4478.9 4496.3 4498.4 4496.1 4506.5 4508.0 4518.3 4518.5 3866.5 4151.8 4300.8 4480.5 4554.7 4076.6 4099.6 4127.7 4128.8 4132.9 5311.0 5341.3 5347.9 5385.8 5400.1 4796.7 4838.7 4861.5 5157.3 5235.4 4928.4 5200.4 5511.5 5565.6 5763.7 4168.9 4461.6 4517.2 4653.8 4672.1 4952.0 4977.3 4992.5 6088.9 6152.1 4432.1 4709.4 4726.7 4733.0 4786.0 4599.5 4816.6 4900.7 4904.3 4914.5 4067.7 4487.4 4564.8 4862.9 5169.6 5397.1 5414.7 5698.3 5748.8 5754.6 1391.0 1407.7 1473.1 1633.2 1645.5 1030.5 1040.5 1087.3 1161.7 1229.4 4996.7 5210.9 5398.2 5407.1 5438.4 5236.9 5286.8 5432.6 6031.4 6111.9 5059.6 5344.7 5364.3 5378.5 5474.9 2735.5 2756.0 2924.5 2946.4 3104.5 5033.7 5407.1 5619.8 5785.2 6232.6 5459.0 5498.9 6013.7 6089.5 6108.7 5089.9 5255.6 6083.9 6124.9 6321.3 5591.6 5932.8 6002.8 6007.7 6034.1 5044.0 5072.6 5329.2 5565.7 5737.9 6225.3 6227.3 6246.3 6251.0 6273.4 4261.3 4567.7 4749.0 4775.7 4804.2 4834.8 5949.8 5956.9 6003.2 6079.5 4825.6 5431.8 5706.2 5707.1 5870.9 5150.3 5466.8 5652.5 5777.8 5818.3 5117.6 5399.8 5632.5 5661.9 5686.5 5199.4 5230.3 5849.6 5865.2 5903.8 5081.6 5607.1 5617.1 5628.3 5638.8 18:23:58 INFO - PROCESS | 1642 | 18:23:58 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:21611.40 Median:17016.03 stddev:31151.80 (183.1%) stddev-sans-first:31199.78 18:23:58 INFO - PROCESS | 1642 | Values: 743.9 758.7 804.3 863.4 868.8 12692.7 16925.2 17106.8 17139.2 17183.6 15058.3 15142.0 15148.3 15163.8 15192.8 980.0 997.2 1004.9 1005.4 1046.2 1024.9 1035.8 1039.6 1042.4 1049.2 1084.1 1084.6 1085.6 1087.2 1087.5 1065.5 1080.8 1081.3 1083.3 1084.2 788.3 800.2 802.5 803.0 803.3 16622.5 17136.3 17239.6 17384.0 17815.2 14817.4 15537.7 15603.4 15740.1 15842.2 11885.9 11918.6 12087.6 12185.9 12396.3 3529.3 3979.1 3980.0 3993.1 4010.4 90685.5 113152.4 117647.4 121018.6 122390.0 11739.9 11770.4 11894.2 11953.1 11986.6 21513.9 26980.3 27113.3 27321.9 27341.9 7496.3 8186.7 8444.5 8596.7 8597.9 4608.5 4621.8 4671.0 4705.3 4859.0 3381.3 3438.2 3468.7 3484.8 3487.6 605.8 658.6 687.8 688.0 688.7 569.5 594.7 595.4 605.6 624.2 10281.7 10955.6 11025.9 11091.7 11171.4 694.3 726.8 733.8 734.5 736.2 4952.4 5347.4 5430.3 5637.3 5653.8 109346.5 110223.4 111075.0 113487.4 113736.0 72110.6 91035.9 92636.0 95535.5 97253.0 83676.2 84684.0 86739.1 86906.0 86924.0 11181.8 11272.0 11353.7 11359.9 11525.3 39923.0 41015.8 46994.3 53800.9 54984.9 56358.4 69110.0 70582.6 73844.3 74785.6 12887.0 13382.5 13465.2 13518.1 13956.8 15399.9 15456.0 15850.0 15927.6 16486.5 14116.6 14286.7 14458.2 14505.3 14974.6 13134.3 13756.6 13881.1 13892.2 13946.0 11274.8 13767.2 13995.4 14159.9 14167.8 9761.5 9923.1 10173.8 10261.6 10388.7 9621.1 9720.0 11145.2 11665.9 11689.8 18:23:58 INFO - PROCESS | 1642 | -------- Summary: end -------- 18:23:58 INFO - PROCESS | 1642 | 18:23:59 INFO - TEST-INFO | 1642: exit 0 18:23:59 INFO - TEST-OK | dromaeo_css | took 1115358ms 18:23:59 INFO - TEST-START | kraken 18:23:59 INFO - operating with platform_type : mac_ 18:23:59 INFO - Initialising browser for kraken test... 18:23:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpio3lM0/profile http://localhost:49241/getInfo.html) 18:24:02 INFO - PROCESS | 1654 | 2017-04-25 18:24:02.005 plugin-container[1656:10012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 18:24:02 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:02 INFO - PROCESS | 1654 | 2017-04-25 18:24:02.006 plugin-container[1656:10012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 18:24:02 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:02 INFO - PROCESS | 1654 | 2017-04-25 18:24:02.006 plugin-container[1656:10012] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:02 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:24:02 INFO - PROCESS | 1654 | colorDepth:24 18:24:02 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:24:02 INFO - PROCESS | 1654 | __metrics 18:24:02 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 18:24:02 INFO - TEST-INFO | 1654: exit 0 18:24:02 INFO - Browser initialized. 18:24:02 INFO - Running cycle 1/1 for kraken test... 18:24:02 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpio3lM0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:04 INFO - PROCESS | 1657 | 2017-04-25 18:24:04.921 plugin-container[1658:10139] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 18:24:04 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:04 INFO - PROCESS | 1657 | 2017-04-25 18:24:04.922 plugin-container[1658:10139] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 18:24:04 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:04 INFO - PROCESS | 1657 | 2017-04-25 18:24:04.922 plugin-container[1658:10139] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:14 INFO - PROCESS | 1657 | RSS: Main: 174469120 18:24:14 INFO - PROCESS | 1657 | 18:24:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:18 INFO - PROCESS | 1657 | RSS: Main: 188047360 18:24:18 INFO - PROCESS | 1657 | 18:24:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 18:24:21 INFO - PROCESS | 1657 | RSS: Main: 196030464 18:24:21 INFO - PROCESS | 1657 | 18:24:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 18:24:25 INFO - PROCESS | 1657 | RSS: Main: 199753728 18:24:25 INFO - PROCESS | 1657 | 18:24:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 18:24:28 INFO - PROCESS | 1657 | RSS: Main: 201134080 18:24:28 INFO - PROCESS | 1657 | 18:24:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:31 INFO - PROCESS | 1657 | RSS: Main: 201502720 18:24:31 INFO - PROCESS | 1657 | 18:24:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:34 INFO - PROCESS | 1657 | RSS: Main: 205303808 18:24:34 INFO - PROCESS | 1657 | 18:24:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:38 INFO - PROCESS | 1657 | RSS: Main: 205115392 18:24:38 INFO - PROCESS | 1657 | 18:24:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 18:24:42 INFO - PROCESS | 1657 | RSS: Main: 205201408 18:24:42 INFO - PROCESS | 1657 | 18:24:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:44 INFO - PROCESS | 1657 | RSS: Main: 204435456 18:24:44 INFO - PROCESS | 1657 | 18:24:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:47 INFO - PROCESS | 1657 | RSS: Main: 204161024 18:24:47 INFO - PROCESS | 1657 | 18:24:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:50 INFO - PROCESS | 1657 | RSS: Main: 204271616 18:24:50 INFO - PROCESS | 1657 | 18:24:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:53 INFO - PROCESS | 1657 | RSS: Main: 204664832 18:24:53 INFO - PROCESS | 1657 | 18:24:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:24:57 INFO - PROCESS | 1657 | RSS: Main: 204410880 18:24:57 INFO - PROCESS | 1657 | 18:24:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 18:24:59 INFO - PROCESS | 1657 | RSS: Main: 206090240 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | __start_tp_report 18:24:59 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 18:24:59 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 18:24:59 INFO - PROCESS | 1657 | |i|pagename|runs| 18:24:59 INFO - PROCESS | 1657 | |0;ai-astar;109;135;184;162;121;121;122;121;121;122 18:24:59 INFO - PROCESS | 1657 | |1;audio-beat-detection;141;109;115;135;133;143;144;144;160;142 18:24:59 INFO - PROCESS | 1657 | |2;audio-dft;196;201;197;199;196;199;200;198;202;185 18:24:59 INFO - PROCESS | 1657 | |3;audio-fft;81;60;61;92;76;86;83;60;77;61 18:24:59 INFO - PROCESS | 1657 | |4;audio-oscillator;87;69;85;72;73;70;81;69;90;75 18:24:59 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;91;91;92;84;136;137;97;127;135;86 18:24:59 INFO - PROCESS | 1657 | |6;imaging-darkroom;96;98;97;97;97;97;98;96;97;98 18:24:59 INFO - PROCESS | 1657 | |7;imaging-desaturate;90;111;148;109;88;144;143;106;144;107 18:24:59 INFO - PROCESS | 1657 | |8;json-parse-financial;56;65;94;93;67;70;67;68;67;68 18:24:59 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;59;78;93;96;63;61;57;61;60;62 18:24:59 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;81;89;90;102;85;87;86;81;84 18:24:59 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;136;141;142;141;132;141;141;180;139 18:24:59 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;164;166;165;163;187;230;165;168;166;166 18:24:59 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;66;63;63;67;66;65;64;65;65;61 18:24:59 INFO - PROCESS | 1657 | __end_tp_report 18:24:59 INFO - PROCESS | 1657 | __start_cc_report 18:24:59 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1008 18:24:59 INFO - PROCESS | 1657 | __end_cc_report 18:24:59 INFO - PROCESS | 1657 | __startTimestamp1493169899075__endTimestamp 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | ------- Summary: start ------- 18:24:59 INFO - PROCESS | 1657 | Number of tests: 14 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:131.80 Median:122.00 stddev:23.16 (19.0%) stddev-sans-first:23.04 18:24:59 INFO - PROCESS | 1657 | Values: 109.0 135.0 184.0 162.0 121.0 121.0 122.0 121.0 121.0 122.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:142.50 stddev:14.87 (10.4%) stddev-sans-first:15.69 18:24:59 INFO - PROCESS | 1657 | Values: 141.0 109.0 115.0 135.0 133.0 143.0 144.0 144.0 160.0 142.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:197.30 Median:199.00 stddev:4.76 (2.4%) stddev-sans-first:5.03 18:24:59 INFO - PROCESS | 1657 | Values: 196.0 201.0 197.0 199.0 196.0 199.0 200.0 198.0 202.0 185.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:73.70 Median:79.00 stddev:12.20 (15.4%) stddev-sans-first:12.65 18:24:59 INFO - PROCESS | 1657 | Values: 81.0 60.0 61.0 92.0 76.0 86.0 83.0 60.0 77.0 61.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.00 stddev:7.96 (10.2%) stddev-sans-first:7.60 18:24:59 INFO - PROCESS | 1657 | Values: 87.0 69.0 85.0 72.0 73.0 70.0 81.0 69.0 90.0 75.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:107.60 Median:88.50 stddev:22.92 (25.9%) stddev-sans-first:23.51 18:24:59 INFO - PROCESS | 1657 | Values: 91.0 91.0 92.0 84.0 136.0 137.0 97.0 127.0 135.0 86.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:97.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 18:24:59 INFO - PROCESS | 1657 | Values: 96.0 98.0 97.0 97.0 97.0 97.0 98.0 96.0 97.0 98.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:119.00 Median:144.00 stddev:23.44 (16.3%) stddev-sans-first:22.39 18:24:59 INFO - PROCESS | 1657 | Values: 90.0 111.0 148.0 109.0 88.0 144.0 143.0 106.0 144.0 107.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:71.50 Median:68.00 stddev:12.20 (17.9%) stddev-sans-first:11.57 18:24:59 INFO - PROCESS | 1657 | Values: 56.0 65.0 94.0 93.0 67.0 70.0 67.0 68.0 67.0 68.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:62.50 stddev:14.62 (23.4%) stddev-sans-first:15.05 18:24:59 INFO - PROCESS | 1657 | Values: 59.0 78.0 93.0 96.0 63.0 61.0 57.0 61.0 60.0 62.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:85.50 stddev:6.04 (7.1%) stddev-sans-first:6.36 18:24:59 INFO - PROCESS | 1657 | Values: 85.0 81.0 89.0 90.0 102.0 85.0 87.0 86.0 81.0 84.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:141.00 stddev:13.37 (9.5%) stddev-sans-first:14.00 18:24:59 INFO - PROCESS | 1657 | Values: 137.0 136.0 141.0 142.0 141.0 132.0 141.0 141.0 180.0 139.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:166.00 stddev:20.86 (12.6%) stddev-sans-first:21.81 18:24:59 INFO - PROCESS | 1657 | Values: 164.0 166.0 165.0 163.0 187.0 230.0 165.0 168.0 166.0 166.0 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.78 (2.7%) stddev-sans-first:1.80 18:24:59 INFO - PROCESS | 1657 | Values: 66.0 63.0 63.0 67.0 66.0 65.0 64.0 65.0 65.0 61.0 18:24:59 INFO - PROCESS | 1657 | -------- Summary: end -------- 18:24:59 INFO - PROCESS | 1657 | 18:24:59 INFO - TEST-INFO | 1657: exit 0 18:24:59 INFO - TEST-OK | kraken | took 60450ms 18:24:59 INFO - SUITE-END | took 1175s 18:25:00 INFO - Completed test suite (00:19:36) 18:25:00 INFO - javascript benchmark 18:25:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7997.748616930547, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2818.7604053655436, 3661.2677464507087, 3682.1715114099316, 3687.11509237783, 3709.7588656737316, 4994.6753461025055, 5854.88290234195, 5860.50185734212, 6266.436020758131, 6269.090981807635, 6190.071489276599, 6209.782657606985, 6228.84427889303, 6267.185265915436, 6272.996320588707, 5581.972090139554, 5641.774329026834, 5645.322561292651, 5647.915281270784, 5711.343195532505, 5649.971750141243, 5674.205611214434, 5696.458836410535, 5698.943010569883, 5747.2241247431475, 4701.459332176809, 5404.297441332621, 5482.424345443731, 5683.573731970098, 5689.459501347365, 5590.91613625796, 5596.2165296858475, 5606.24315717377, 5614.494695477406, 5636.126400407943, 5534.252875861747, 5616.66300022, 5639.0, 5650.915236271459, 5730.914036289459, 6289.685515724195, 6312.0, 6331.240251169843, 6332.176817013758, 6345.33373995733, 6368.808935731936, 6395.712192951329, 6647.824529278316, 6671.099401580773, 6721.63031033293, 5421.701806400646, 6607.900881486781, 6783.152105986752, 6803.353681400259, 6804.76183333581, 6307.747690092437, 6339.809805705836, 6365.077063825765, 6372.745090196387, 6464.41820236181, 5688.402717714663, 6620.437262832616, 6677.265500794909, 6922.134733158356, 6941.514094013472, 5474.890502190014, 6442.5812322199845, 6463.418292353711, 6478.514111441661, 6484.513661475408, 6714.194296684429, 6715.2613212546585, 6745.190577130776, 6748.392644661906, 6768.593884366954, 6633.336666333328, 6645.368689974444, 6645.468362530986, 6664.100346453167, 6666.933330666729, 5351.545118664879, 6042.939570604326, 6386.744530218833, 6469.838254043687, 6547.607143571306, 4768.833090841804, 5189.922151167735, 5195.506426889439, 5328.254044433782, 5349.919751203735, 4715.151272770866, 4716.127516409407, 4720.811167553328, 4729.0, 4745.217039188564, 6140.657627863787, 6234.604603754342, 6371.267304259976, 6388.4569811566535, 6397.232332120082, 6599.967000165065, 6608.900866487006, 6682.331766823376, 6690.59856408617, 6719.899201511981, 6319.715612797435, 6331.2085989251345, 6342.270638876496, 6344.524160687967, 6348.174737284124, 5931.406859314034, 5945.227120474311, 5946.643201407929, 5954.851128721816, 5956.7319470623925, 6681.031250468654, 6723.63020033893, 6779.661016949034, 6913.101296831581, 6987.441004719712, 6745.5952642841585, 6755.189377274758, 6778.423833974168, 6782.966085169543, 6783.694733736895, 6859.9314006859295, 6940.514164008471, 6978.0, 7005.754798582026, 7023.789286321418, 6395.264544577247, 6435.678216089083, 6522.869542609216, 6530.151080359523, 6686.297938716365, 6733.922572388395, 6738.528303018742, 6743.123393958754, 6758.324167583399, 6762.898556521558, 4855.611551075976, 6495.935040649533, 6523.282438931618, 6526.967365163144, 6557.0, 5639.210510528447, 6279.748810047547, 6335.429811316819, 6543.0, 6564.573302735307, 5736.942630573641, 6116.1836049259855, 6345.96827015862, 6346.492280617447, 6418.679066046772, 6543.934560654333, 6550.705218265094, 6569.211694596679, 6579.243387010555, 6589.6705164740615, 6145.955187618027, 6172.105044768573, 6340.9048864268, 6405.679716014088, 6412.294647588668], "value": 6080.4790581292855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.1793698679401, 823.5429336718123, 854.4531499840103, 923.9815203695922, 935.5930170375883, 37845.0, 38943.0, 39463.605363946386, 40453.0, 42328.99999999996, 40877.7956110219, 40930.79534602323, 41326.58673413265, 41326.793366033125, 41389.0, 8266.751997440071, 8396.790080247982, 11411.31532108072, 11709.824352634718, 11908.40457977102, 9503.429794212334, 11630.941845290761, 11633.127515436376, 11654.30074195551, 11659.766804663901, 11593.36236506996, 11641.01051410631, 11663.941680291588, 11673.883261167408, 11680.299182049104, 9021.684241051564, 9620.182284505825, 11545.249558778736, 11600.187986840925, 11623.476943537518, 11389.088872890148, 11401.600943967005, 11409.0, 11409.54361825526, 11460.598879039277, 28350.157239779553, 35382.0, 35799.64200358015, 35878.46182307268, 35966.0, 3880.417937309398, 3904.403571624866, 3940.1922605866016, 3950.743201691881, 4022.0950286185375, 26259.606105908428, 26466.73533264681, 26498.337541561614, 27670.893164273406, 27747.58378624302, 3210.598675165604, 3283.507473878937, 3291.883650476193, 3312.5362449257123, 3417.538632284635, 349036.2548187268, 350851.0, 351222.0, 351242.99999999744, 351535.0, 43957.56042439599, 44493.332600011025, 44549.331760023626, 44623.330650040276, 44772.552274477486, 46711.0, 46951.5304846954, 47060.7646961763, 47061.82345441391, 47204.7639761799, 13663.111897726814, 14049.367778449994, 14103.85896141046, 14114.85885141156, 14147.22190279524, 11904.583339583072, 12111.091668124925, 12124.454399552042, 12129.332886691316, 12133.5146594137, 12215.328156951453, 12232.81650775239, 12266.71199524055, 12268.938655306667, 12366.814497782541, 5850.268716410449, 5876.647401155944, 5901.7639294427745, 5910.320313163955, 5922.230110085661, 1036.461040259076, 1108.1965574958251, 1125.7298248420484, 1151.3667482884548, 1167.2996202278734, 27682.446351072867, 27830.72169278322, 28195.859020704764, 28217.71782282192, 28308.858455708003, 1072.769354588767, 1258.622413276032, 1278.2202856257675, 1291.3930452687223, 1292.2440372382262, 13004.674883127998, 13950.651233719238, 14242.572722818331, 14412.351444184827, 14671.706565868622, 385915.0, 421021.0, 461765.0, 463043.0, 465196.0, 59496.0, 59550.0, 59713.701431493435, 59782.402175978554, 59893.0, 1294.1975974895436, 1330.1420583723443, 1492.9477468288558, 1506.6835964447368, 1524.4283393727353, 30409.087727368213, 31909.521357179197, 31954.36091278208, 31980.840095799373, 32585.674143258264, 44264.0, 44376.556234437245, 44414.77792611016, 44455.77772111119, 44471.0, 439323.0, 440397.0, 441222.0, 441225.0, 444031.0, 26569.60145597856, 31757.0, 33244.0, 33251.50122748112, 33323.16692082717, 44977.32534011927, 46340.30489542592, 47181.0, 47297.0, 47686.04627907353, 31005.534916976714, 31509.684903150675, 31641.0, 32065.0, 32710.672893271716, 25190.748092519574, 31459.213519662193, 31459.685403145675, 31568.210794730316, 31648.841755791073, 36395.45406818953, 36720.449193262655, 37259.0, 37586.43620345752, 37641.81179094087, 30838.845805770827, 31128.377432451678, 31234.21914452157, 31965.840170798998, 32176.839115804272, 22023.77976220217, 25130.62304065477, 25309.240722778348, 25396.619050713885, 25730.742692572836], "value": 19102.64587254132, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.20560537780887, 424.940508328834, 434.67182277380573, 440.4143809975874, 441.9977900110499, 17965.28138874445, 18070.0, 20779.85710785906, 21195.47011324718, 23103.0, 22627.868606569646, 23226.65160022601, 23645.5270894582, 23723.644145337792, 24147.517049658996, 6794.184697836266, 6967.059446974644, 7131.572105673677, 7192.640367981606, 7198.8200294992785, 5715.885682286352, 7103.680334384965, 7127.679254433563, 7141.107361579802, 7153.964230178842, 6769.796906092825, 7061.964690176567, 7064.964675176617, 7076.469264805134, 7120.394766444832, 5479.890402191933, 6907.378335949788, 7003.089598352232, 7010.684519196649, 7036.577805331696, 6767.560108592926, 6851.897221541631, 6852.897206541906, 6871.725130994754, 6885.827854303632, 27496.17511474659, 27578.724212758018, 28096.15711528637, 28194.436111277864, 29158.70841291581, 3037.741791947665, 3100.953485697716, 3142.104500217427, 3145.685431456859, 3147.2603938074535, 16994.23525941323, 17166.742498862528, 17183.3985810496, 17227.22477488516, 17360.565985850328, 2137.8931053447263, 2176.1077958037285, 2191.583599116179, 2203.4711669199437, 2262.1290803040783, 109519.0, 142248.0, 142490.0, 144799.0, 145129.99999999895, 22774.43063923382, 22948.541029179323, 23126.768732312798, 23415.648765268535, 23524.0, 36697.26605467876, 36887.81556092256, 37269.068273293386, 37790.0, 37857.0, 11107.111431085494, 11250.481185040078, 11457.608722464722, 11458.484368203452, 11864.99147572466, 9243.491607961627, 9294.628214871329, 9308.720738377859, 9445.622175112918, 9490.525473726286, 8464.44981076228, 9042.63829446828, 11122.55509779616, 11256.26834255787, 11369.602063927723, 3894.6689531389584, 4270.978645106817, 4284.614384705392, 4669.789859456333, 4694.906101877943, 1051.8369652703843, 1063.3726101600093, 1079.8542196803487, 1095.956161753537, 1141.0, 23575.528489430115, 23622.29133126009, 23713.644295335584, 23855.64216536753, 23931.162409315937, 949.8100379924043, 1109.378747901178, 1114.8717897441898, 1157.5948418053613, 1161.6050542815483, 12147.907500510597, 12403.813942790865, 12519.436625351698, 12553.435095420546, 12676.302803345905, 663616.0, 665629.0, 702109.0, 716373.0, 739598.0, 124064.0, 137883.0, 143454.28272858777, 144743.0, 147104.0, 1265.2724683307135, 1277.9297138657464, 1288.7486940046788, 1299.616613099157, 1304.4912484131007, 18108.90945545264, 19792.0, 20915.058822353334, 21129.0, 21171.8941405292, 44148.7792561048, 55250.44749552453, 60617.48456288628, 62150.37849621446, 63389.36610633835, 81755.59122204351, 82326.0, 84662.0, 84666.0, 84859.57570212109, 8224.876626850722, 11884.465199066235, 12192.841680040381, 12305.56930507428, 12835.486580536674, 13961.930190348983, 14023.859761402255, 14459.98780085384, 14466.421343146156, 14574.85425145735, 11729.530818767495, 11787.11596630239, 11998.520059197534, 12132.878671213175, 12242.143049986771, 7790.766277011472, 11205.88794112081, 11250.83123753128, 11422.885771142515, 11732.178747487595, 10028.849567256642, 11960.76078478443, 12004.519819207135, 12270.938645306716, 12277.447514861675, 12631.305278209788, 12662.0, 12817.1668841525, 12994.220346779224, 13048.673783155496, 10948.890511095105, 11241.932016458424, 11607.593734219261, 11818.527258909891, 12041.157119001937], "value": 12090.561852503992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1632.2817960097564, 1641.5403686967645, 1658.56048147241, 1660.3275673352293, 1667.533090734594, 11056.557737690482, 11335.943320283408, 11610.825837612421, 11672.533098676053, 11692.29846209228, 11502.137339699515, 11636.301821890693, 11641.0, 11670.883291167085, 11673.357965311905, 2418.6492958521003, 2462.285937078254, 2472.3077538289217, 2473.4558397152596, 2473.468204336067, 2089.4538041849073, 2407.759224077587, 2408.3015925381706, 2466.124525793344, 2473.703155621328, 2394.521095780842, 2441.0967941861445, 2451.6935383077116, 2470.6170543565686, 2472.159465781634, 2351.588472017407, 2396.916107936231, 2414.396400899775, 2426.8472475574135, 2428.138011006094, 2024.3117340104288, 2394.150076722773, 2394.616861302183, 2418.806495480357, 2421.7093948726088, 10350.53422595985, 11074.723131921686, 11485.368304743235, 11876.584319548774, 11903.523859045628, 5923.555733320019, 6012.398760123996, 6099.359567245419, 6111.480524155452, 6115.938840611581, 3533.628968958248, 3693.0582701411236, 3709.2766910452474, 3742.5321834770652, 3762.1347090169143, 1236.3509157692283, 1239.1264158768047, 1271.6185144456626, 1291.9935400322938, 1295.6372215779597, 14111.576652700436, 14388.0, 15266.0, 16167.676646467005, 16334.591635209095, 4737.8341758038305, 4750.667453278306, 4767.237242041257, 4888.388951381077, 4893.755312234374, 11928.940355298168, 12040.81938770919, 12092.274463532216, 12112.69718257033, 12117.818232726517, 5971.731272092767, 5984.700764961822, 5988.610740301867, 5990.700464976734, 5997.670128142907, 5375.865603359869, 5403.756830942617, 5411.891762164813, 5446.101393270145, 5462.836114916479, 3190.744740420761, 3994.021464741157, 4024.054347228399, 4082.897927551835, 4121.0, 1040.843873418993, 1043.0977204717865, 1045.560864436923, 1054.4200689620677, 1056.0231785598291, 944.7165850244899, 962.764122789921, 976.1458723616835, 994.2642444591095, 1000.9840856452627, 6631.502637302222, 6669.866602667919, 6691.6951194516605, 6766.695498702472, 6794.946783248604, 971.4511301114848, 1027.6095083868083, 1034.2036631793476, 1039.184240371305, 1059.2796898109204, 3483.042163405084, 4067.8576249831112, 4362.0, 4365.628921541641, 4418.889527761831, 13246.20522768637, 14372.0, 14564.563063108124, 14565.271736413137, 14567.562973110824, 14058.71882562343, 14292.571222863331, 14360.856391435951, 14436.278186090654, 14465.204413757134, 8960.19358257773, 9141.177294043568, 9822.410655360558, 10384.740381490523, 10585.0, 6480.351964803482, 6959.652017399211, 6978.302169782981, 7072.858542829012, 7246.855062898818, 12413.93793031029, 12867.099303049072, 13039.804402933774, 13049.739005220032, 13114.213147211198, 12239.632811015685, 13252.00609954238, 13380.933095334462, 13401.396937138048, 13420.127691700009, 9442.858357124785, 9680.709578712649, 9709.70870873875, 9732.270079743907, 9746.171575416169, 10209.38743675382, 10617.256792024484, 10680.57277708914, 10702.304350217211, 10789.784204316027, 9003.459792412476, 9130.863037054582, 9188.127127922837, 9204.493752843659, 9408.01215872351, 8913.153250441197, 8961.955190224007, 8973.147550982647, 9040.547972601475, 9067.229285510806, 8223.301019413417, 8320.625571849161, 8378.664853405795, 8404.70583529598, 8420.789480263042, 5914.4381283777975, 5959.880802384015, 5994.430529099728, 6090.908636370369, 6205.069239614094, 6091.0, 6212.565120441525, 6232.09634602971, 6297.905531417124, 6310.5267104968225], "value": 5295.21983516193, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.79387882573394, 379.9323899841444, 382.70149283558817, 393.15864050931003, 399.303215888275, 5342.592361301922, 5406.621536492442, 5507.870886468274, 5514.904336352186, 5526.0, 4357.651387888969, 4395.68129561132, 4959.900801983958, 4983.651144419883, 5339.22581225722, 4009.6591789697836, 4371.715838470505, 4427.645788336941, 4459.26422140348, 4513.3455648930885, 3589.9282014359696, 4445.888203103532, 4478.910421791562, 4496.325551167335, 4498.392716983214, 4496.123255965088, 4506.504284528699, 4507.977460112695, 4518.322251662261, 4518.508892065611, 3866.4780254665543, 4151.750894946313, 4300.827966881321, 4480.507144214133, 4554.72467709042, 4076.5923407659293, 4099.647116451564, 4127.711060225785, 4128.814203360856, 4132.884121287266, 5311.0, 5341.252224688546, 5347.946520534783, 5385.8259848617245, 5400.081986062379, 4796.664233503656, 4838.709677419366, 4861.492388320439, 5157.252198431206, 5235.439900867409, 4928.383952005999, 5200.375954885438, 5511.5039646432015, 5565.554755619541, 5763.665715385276, 4168.937465938014, 4461.620762235214, 4517.170545928871, 4653.7905794239, 4672.065586882637, 4951.9752401237765, 4977.278294647292, 4992.525710057539, 6088.9391106088815, 6152.138700581922, 4432.113577284556, 4709.434867815884, 4726.692764970266, 4732.976335118371, 4786.0, 4599.471060827981, 4816.614670826326, 4900.705957642553, 4904.2643603459055, 4914.459409464956, 4067.6814215896657, 4487.4069705254815, 4564.790330562404, 4862.878428039327, 5169.612279079084, 5397.136458166753, 5414.675119492843, 5698.262030080865, 5748.815450128925, 5754.568407369464, 1390.9721805563831, 1407.7114191590904, 1473.130852796855, 1633.2405431474422, 1645.5474744445373, 1030.4847576211894, 1040.5057597641212, 1087.3041253597703, 1161.7095726068483, 1229.416027386984, 4996.675216110941, 5210.925553923204, 5398.218587865995, 5407.080796264644, 5438.428964958702, 5236.947630523646, 5286.841394758163, 5432.619716619799, 6031.390806549951, 6111.938880611137, 5059.569936555434, 5344.732763361816, 5364.275822763877, 5378.515933565998, 5474.863128421742, 2735.480258750831, 2756.043209321364, 2924.4735947529225, 2946.4401763664837, 3104.487759519699, 5033.672811267329, 5407.053765591006, 5619.775208991595, 5785.17766903424, 6232.637153992224, 5458.962797068623, 5498.862528436821, 6013.737115205856, 6089.482393996472, 6108.670774427947, 5089.923651145162, 5255.5532779713385, 6083.9391606084255, 6124.938750612437, 6321.302038307642, 5591.608587398843, 5932.762689492372, 6002.8294482575475, 6007.729652165575, 6034.09488576717, 5044.0, 5072.644914855925, 5329.228801023523, 5565.686592890543, 5737.942620573908, 6225.346338634378, 6227.252729672468, 6246.250449946036, 6250.9998400255745, 6273.404026617464, 4261.31818908973, 4567.725936443824, 4749.0, 4775.71345719258, 4804.231322988305, 4834.758262086812, 5949.762009519571, 5956.910646340221, 6003.199040287809, 6079.452849243589, 4825.6139508839915, 5431.8098866539485, 5706.176861502886, 5707.143928410772, 5870.853228669147, 5150.330457040561, 5466.835994920159, 5652.484644333396, 5777.782218980084, 5818.301803783573, 5117.641765076409, 5399.83800485986, 5632.464915832989, 5661.915071274017, 5686.459786320293, 5199.4020687621405, 5230.320058392384, 5849.5612829037145, 5865.178874957424, 5903.793367232126, 5081.568066714371, 5607.084316155658, 5617.129344951539, 5628.324601047737, 5638.802641907513], "value": 4377.358336614221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9293267139619, 758.6510205305563, 804.2841870735047, 863.4258218284839, 868.7958329792499, 12692.682682932931, 16925.238364273606, 17106.82893171068, 17139.228734706954, 17183.570410739736, 15058.34358220595, 15142.0, 15148.318325675344, 15163.848361516382, 15192.772108418383, 980.0248752491264, 997.2371136080868, 1004.9246306527003, 1005.4269066632023, 1046.1735229168946, 1024.902634249745, 1035.7824856780048, 1039.6049501189536, 1042.4422933730455, 1049.176396528725, 1084.0514549768952, 1084.6203828659984, 1085.6091806949491, 1087.2443651662097, 1087.456271864068, 1065.4992153687792, 1080.8324709670014, 1081.3187691754224, 1083.3391631105019, 1084.2247792828166, 788.282662776876, 800.1878093734864, 802.5104686141449, 803.0203152154362, 803.337246771416, 16622.50132496027, 17136.31454741809, 17239.569010774707, 17384.0, 17815.198316075806, 14817.407303707947, 15537.689246215126, 15603.375864965388, 15740.134292613902, 15842.207889605474, 11885.881141188564, 11918.582849600223, 12087.576934807239, 12185.878141218564, 12396.318202498858, 3529.2588556403184, 3979.1047014421815, 3979.9602003980167, 3993.0815912340036, 4010.418489319062, 90685.54657226738, 113152.4342378291, 117647.4117629415, 121018.57962840782, 122389.99999999911, 11739.88260117405, 11770.411479426166, 11894.16740828151, 11953.103517236243, 11986.64040078799, 21513.892430537748, 26980.32549186286, 27113.322166945985, 27321.86339068292, 27341.863290683817, 7496.325330720261, 8186.713465028814, 8444.451110677786, 8596.699115531052, 8597.871031934526, 4608.493065762764, 4621.838235661803, 4670.95329046712, 4705.270683044134, 4859.003904199631, 3381.3406385754542, 3438.157651375379, 3468.7051600613727, 3484.8257587120543, 3487.5814902211896, 605.8424809549549, 658.601546064624, 687.8246047257917, 688.0470548290654, 688.7072993977539, 569.4908492494839, 594.669958173218, 595.3619225930432, 605.5639939243738, 624.1947887225429, 10281.74295642615, 10955.616553420592, 11025.889741102646, 11091.667249982513, 11171.38557379352, 694.2675477371321, 726.7928640337478, 733.8275505256261, 734.5225603357753, 736.2122528894032, 4952.380952380952, 5347.406507843692, 5430.266913966564, 5637.3235211774845, 5653.802116925888, 109346.45326773316, 110223.44888275507, 111075.0, 113487.43256283666, 113736.0, 72110.63944680244, 91035.90338434787, 92636.0, 95535.52232238794, 97253.0274697244, 83676.16323836806, 84684.0, 86739.13260867436, 86906.0, 86924.0, 11181.832272515756, 11272.0, 11353.659390218307, 11359.886401135884, 11525.308481491138, 39923.0, 41015.794921025205, 46994.29508557306, 53800.92398152094, 54984.90030199454, 56358.436415636956, 69110.0, 70582.64708676423, 73844.2615573859, 74785.6260718693, 12887.033472489788, 13382.531611393555, 13465.192088474723, 13518.121322114031, 13956.790648140082, 15399.923000385375, 15456.0, 15850.048997059752, 15927.601809954844, 16486.505404837873, 14116.647083822987, 14286.714265714836, 14458.20479873575, 14505.274736262934, 14974.625634359229, 13134.277614731282, 13756.608498628628, 13881.097728647606, 13892.16647001183, 13946.0, 11274.830877537008, 13767.242801646005, 13995.440182392998, 14159.938004649899, 14167.787483187967, 9761.511924403609, 9923.057309555581, 10173.796524069625, 10261.640842570474, 10388.740281493023, 9621.114943274508, 9719.951400242953, 11145.164112691742, 11665.88334116648, 11689.766204675689], "value": 8038.834450758249, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 135.0, 184.0, 162.0, 121.0, 121.0, 122.0, 121.0, 121.0, 122.0], "value": 131.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 115.0, 135.0, 133.0, 143.0, 144.0, 144.0, 160.0, 142.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 197.0, 199.0, 196.0, 199.0, 200.0, 198.0, 202.0, 185.0], "value": 197.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 92.0, 76.0, 86.0, 83.0, 60.0, 77.0, 61.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 85.0, 72.0, 73.0, 70.0, 81.0, 69.0, 90.0, 75.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 84.0, 136.0, 137.0, 97.0, 127.0, 135.0, 86.0], "value": 107.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 97.0, 97.0, 97.0, 98.0, 96.0, 97.0, 98.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 111.0, 148.0, 109.0, 88.0, 144.0, 143.0, 106.0, 144.0, 107.0], "value": 119.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 94.0, 93.0, 67.0, 70.0, 67.0, 68.0, 67.0, 68.0], "value": 71.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 78.0, 93.0, 96.0, 63.0, 61.0, 57.0, 61.0, 60.0, 62.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 89.0, 90.0, 102.0, 85.0, 87.0, 86.0, 81.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 141.0, 142.0, 141.0, 132.0, 141.0, 141.0, 180.0, 139.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 165.0, 163.0, 187.0, 230.0, 165.0, 168.0, 166.0, 166.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 67.0, 66.0, 65.0, 64.0, 65.0, 65.0, 61.0], "value": 64.5, "unit": "score"}]}]} 18:25:00 INFO - Return code: 0 18:25:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:00 ERROR - # TBPL SUCCESS # 18:25:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:00 INFO - 2017-04-25 18:25:00.421 screenresolution[1662:10511] starting screenresolution argv=screenresolution get 18:25:00 INFO - 2017-04-25 18:25:00.423 screenresolution[1662:10511] Display 0: 1600x1200x32@60 18:25:00 INFO - 2017-04-25 18:25:00.429 screenresolution[1663:10514] starting screenresolution argv=screenresolution list 18:25:00 INFO - Available Modes on Display 0 18:25:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:00 INFO - Intel Iris: 18:25:00 INFO - Chipset Model: Intel Iris 18:25:00 INFO - Type: GPU 18:25:00 INFO - Bus: Built-In 18:25:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:00 INFO - Vendor: Intel (0x8086) 18:25:00 INFO - Device ID: 0x0a2e 18:25:00 INFO - Revision ID: 0x0009 18:25:00 INFO - Displays: 18:25:00 INFO - Display: 18:25:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:00 INFO - Display Serial Number: 1600x1200 60 18:25:00 INFO - Main Display: Yes 18:25:00 INFO - Mirror: Off 18:25:00 INFO - Online: Yes 18:25:00 INFO - Rotation: Supported 18:25:00 INFO - Return code: 0 18:25:00 INFO - Running post-action listener: _resource_record_post_action 18:25:00 INFO - [mozharness: 2017-04-26 01:25:00.664006Z] Finished run-tests step (success) 18:25:00 INFO - Running post-run listener: _resource_record_post_run 18:25:00 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 40626176; Write bytes: 425639424; Read time: 396; Write time: 1761 18:25:00 INFO - TinderboxPrint: CPU usage
54.4% 18:25:00 INFO - TinderboxPrint: I/O read bytes / time
40,626,176 / 396 18:25:00 INFO - TinderboxPrint: I/O write bytes / time
425,639,424 / 1,761 18:25:00 INFO - TinderboxPrint: CPU idle
2,176.2 (45.6%) 18:25:00 INFO - TinderboxPrint: CPU system
959.5 (20.1%) 18:25:00 INFO - TinderboxPrint: CPU user
1,633.9 (34.3%) 18:25:00 INFO - TinderboxPrint: Swap in / out
163,192,832 / 0 18:25:00 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 115333632; Write bytes: 115423232; Read time: 10563; Write time: 272 18:25:00 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 38803456; Write bytes: 299431424; Read time: 376; Write time: 1456 18:25:01 INFO - Running post-run listener: _upload_blobber_files 18:25:01 INFO - Blob upload gear active. 18:25:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:01 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 18:25:01 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'] 18:25:01 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 18:25:01 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:25:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:25:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:03 INFO - (blobuploader) - INFO - Done attempting. 18:25:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:25:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:25:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:03 INFO - (blobuploader) - INFO - Done attempting. 18:25:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:25:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:04 INFO - (blobuploader) - INFO - Done attempting. 18:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:08 INFO - (blobuploader) - INFO - Done attempting. 18:25:08 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:08 INFO - Return code: 0 18:25:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89baba9e79e491c5bbae115e9d5a4ebfdcbfdf7a1b0a64bee9a311bfff8eba9904bdcaeb0c3063a4fefcc7eaa83c2f41020cba0ce3786d1baffbe07bd4b55902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce34dbd0c4271a7f70109068732f00c21b05a40c8c108484322cde9d661c128ad309424a2913a8968cb6154f6e36d6010a1d2ee0cf9aa716496fa071eedd0c83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cae903c4b0834eb2090828535a1b8e47fbd816b7354775282fc6eb7d127d4d2bd5b960aa43bfe66a52388bebc42c46c22c3546303954b15701b699c8d6eda2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4540d31d91ec675ecf2c513646a2f2b294e746f0c31b7af0a14ee4de15a8282b4a1d5a12606d29b5a2af088d900febb42c2f0861c52af0abbddf453a179d3d2"} 18:25:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:08 INFO - Contents: 18:25:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89baba9e79e491c5bbae115e9d5a4ebfdcbfdf7a1b0a64bee9a311bfff8eba9904bdcaeb0c3063a4fefcc7eaa83c2f41020cba0ce3786d1baffbe07bd4b55902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce34dbd0c4271a7f70109068732f00c21b05a40c8c108484322cde9d661c128ad309424a2913a8968cb6154f6e36d6010a1d2ee0cf9aa716496fa071eedd0c83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cae903c4b0834eb2090828535a1b8e47fbd816b7354775282fc6eb7d127d4d2bd5b960aa43bfe66a52388bebc42c46c22c3546303954b15701b699c8d6eda2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4540d31d91ec675ecf2c513646a2f2b294e746f0c31b7af0a14ee4de15a8282b4a1d5a12606d29b5a2af088d900febb42c2f0861c52af0abbddf453a179d3d2"} 18:25:08 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:08 INFO - Copying logs to upload dir... 18:25:08 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.985659 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-25 18:25:08.339885) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 18:25:08.343097) ========= 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.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/89baba9e79e491c5bbae115e9d5a4ebfdcbfdf7a1b0a64bee9a311bfff8eba9904bdcaeb0c3063a4fefcc7eaa83c2f41020cba0ce3786d1baffbe07bd4b55902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce34dbd0c4271a7f70109068732f00c21b05a40c8c108484322cde9d661c128ad309424a2913a8968cb6154f6e36d6010a1d2ee0cf9aa716496fa071eedd0c83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cae903c4b0834eb2090828535a1b8e47fbd816b7354775282fc6eb7d127d4d2bd5b960aa43bfe66a52388bebc42c46c22c3546303954b15701b699c8d6eda2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4540d31d91ec675ecf2c513646a2f2b294e746f0c31b7af0a14ee4de15a8282b4a1d5a12606d29b5a2af088d900febb42c2f0861c52af0abbddf453a179d3d2"} build_url:https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/IvArxU-DSNmYIR-QqEboTg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89baba9e79e491c5bbae115e9d5a4ebfdcbfdf7a1b0a64bee9a311bfff8eba9904bdcaeb0c3063a4fefcc7eaa83c2f41020cba0ce3786d1baffbe07bd4b55902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce34dbd0c4271a7f70109068732f00c21b05a40c8c108484322cde9d661c128ad309424a2913a8968cb6154f6e36d6010a1d2ee0cf9aa716496fa071eedd0c83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cae903c4b0834eb2090828535a1b8e47fbd816b7354775282fc6eb7d127d4d2bd5b960aa43bfe66a52388bebc42c46c22c3546303954b15701b699c8d6eda2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4540d31d91ec675ecf2c513646a2f2b294e746f0c31b7af0a14ee4de15a8282b4a1d5a12606d29b5a2af088d900febb42c2f0861c52af0abbddf453a179d3d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 18:25:08.378754) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:25:08.379102) ========= 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.nnPa6O8DXf/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.5zfnld5aCv/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 18:25:08.427591) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 18:25:08.427928) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 18:25:08.428262) ========= ========= Total master_lag: 1.92 =========