builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0149 starttime: 1471837619.42 results: success (0) buildid: 20160821184714 builduid: e45e9bbc5a7b4cb3a602f26bd7edd548 revision: 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.419882) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.420364) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.420657) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.446315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.446716) ========= 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.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-21 20:46:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.6M=0.001s 2016-08-21 20:46:59 (16.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126275 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.596477) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.596816) ========= 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.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.029735 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:46:59.648109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-21 20:46:59.648455) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-21 20:46:59,725 truncating revision to first 12 chars 2016-08-21 20:46:59,725 Setting DEBUG logging. 2016-08-21 20:46:59,725 attempt 1/10 2016-08-21 20:46:59,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/64f4ea57b6fd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-21 20:47:01,490 unpacking tar archive at: fx-team-64f4ea57b6fd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.007369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-21 20:47:01.678082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 20:47:01.678406) ========= script_repo_revision: 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 20:47:01.678803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 20:47:01.679070) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 20:47:01.696527) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-21 20:47:01.696908) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:01 INFO - MultiFileLogger online at 20160821 20:47:01 in /builds/slave/test 20:47:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:47:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:47:01 INFO - {'append_to_log': False, 20:47:01 INFO - 'base_work_dir': '/builds/slave/test', 20:47:01 INFO - 'blob_upload_branch': 'Fx-Team', 20:47:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:47:01 INFO - 'branch': 'Fx-Team', 20:47:01 INFO - 'buildbot_json_path': 'buildprops.json', 20:47:01 INFO - 'config_files': ('talos/mac_config.py',), 20:47:01 INFO - 'default_actions': ('clobber', 20:47:01 INFO - 'read-buildbot-config', 20:47:01 INFO - 'download-and-extract', 20:47:01 INFO - 'populate-webroot', 20:47:01 INFO - 'create-virtualenv', 20:47:01 INFO - 'install', 20:47:01 INFO - 'run-tests'), 20:47:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:47:01 INFO - 'download_minidump_stackwalk': True, 20:47:01 INFO - 'download_symbols': 'ondemand', 20:47:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:47:01 INFO - 'tooltool.py': '/tools/tooltool.py', 20:47:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:47:01 INFO - '/tools/misc-python/virtualenv.py')}, 20:47:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:47:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:47:01 INFO - 'installer_path': 'installer.exe', 20:47:01 INFO - 'log_level': 'info', 20:47:01 INFO - 'log_name': 'talos', 20:47:01 INFO - 'log_to_console': True, 20:47:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:47:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:47:01 INFO - 'opt_config_files': (), 20:47:01 INFO - 'pip_index': False, 20:47:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:47:01 INFO - 'cmd': ('bash', 20:47:01 INFO - '-c', 20:47:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:47:01 INFO - 'enabled': True, 20:47:01 INFO - 'halt_on_failure': False, 20:47:01 INFO - 'name': 'check_screen_resolution'},), 20:47:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:47:01 INFO - 'cmd': ('bash', 20:47:01 INFO - '-c', 20:47:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:47:01 INFO - 'enabled': True, 20:47:01 INFO - 'halt_on_failure': False, 20:47:01 INFO - 'name': 'check_screen_resolution'},), 20:47:01 INFO - 'run_cmd_checks_enabled': True, 20:47:01 INFO - 'sps_profile': False, 20:47:01 INFO - 'sps_profile_interval': 0, 20:47:01 INFO - 'suite': 'dromaeojs', 20:47:01 INFO - 'system_bits': '32', 20:47:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:47:01 INFO - 'title': 't-yosemite-r7-0149', 20:47:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:47:01 INFO - 'use_talos_json': True, 20:47:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:47:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:47:01 INFO - 'work_dir': 'build'} 20:47:01 INFO - [mozharness: 2016-08-22 03:47:01.895708Z] Running clobber step. 20:47:01 INFO - Running pre-action listener: _resource_record_pre_action 20:47:01 INFO - Running main action method: clobber 20:47:01 INFO - rmtree: /builds/slave/test/build 20:47:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:47:04 INFO - Running post-action listener: _resource_record_post_action 20:47:04 INFO - [mozharness: 2016-08-22 03:47:04.213014Z] Finished clobber step (success) 20:47:04 INFO - [mozharness: 2016-08-22 03:47:04.213232Z] Running read-buildbot-config step. 20:47:04 INFO - Running pre-action listener: _resource_record_pre_action 20:47:04 INFO - Running main action method: read_buildbot_config 20:47:04 INFO - Using buildbot properties: 20:47:04 INFO - { 20:47:04 INFO - "project": "", 20:47:04 INFO - "product": "firefox", 20:47:04 INFO - "script_repo_revision": "production", 20:47:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:47:04 INFO - "repository": "", 20:47:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:47:04 INFO - "buildid": "20160821184714", 20:47:04 INFO - "pgo_build": "False", 20:47:04 INFO - "basedir": "/builds/slave/test", 20:47:04 INFO - "buildnumber": 922, 20:47:04 INFO - "slavename": "t-yosemite-r7-0149", 20:47:04 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:47:04 INFO - "platform": "yosemite_r7", 20:47:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:47:04 INFO - "branch": "fx-team", 20:47:04 INFO - "repo_path": "integration/fx-team", 20:47:04 INFO - "revision": "64f4ea57b6fde8ed315f5bc8c9caefe389bb8923", 20:47:04 INFO - "stage_platform": "macosx64", 20:47:04 INFO - "builduid": "e45e9bbc5a7b4cb3a602f26bd7edd548", 20:47:04 INFO - "slavebuilddir": "test" 20:47:04 INFO - } 20:47:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:47:04 INFO - Running post-action listener: _resource_record_post_action 20:47:04 INFO - [mozharness: 2016-08-22 03:47:04.218680Z] Finished read-buildbot-config step (success) 20:47:04 INFO - [mozharness: 2016-08-22 03:47:04.218923Z] Running download-and-extract step. 20:47:04 INFO - Running pre-action listener: _resource_record_pre_action 20:47:04 INFO - Running main action method: download_and_extract 20:47:04 INFO - mkdir: /builds/slave/test/build/tests 20:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:04 INFO - trying https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:47:04 INFO - Downloading https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 20:47:05 INFO - Downloaded 1199 bytes. 20:47:05 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:47:05 INFO - Using the following test package requirements: 20:47:05 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:47:05 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:47:05 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:47:05 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'jsshell-mac.zip'], 20:47:05 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:47:05 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:47:05 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:47:05 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:47:05 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:47:05 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:47:05 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:47:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:05 INFO - trying https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:47:05 INFO - Downloading https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:47:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:47:06 INFO - Downloaded 16351778 bytes. 20:47:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:47:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:12 INFO - trying https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:47:12 INFO - Downloading https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:47:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:47:13 INFO - Downloaded 16351778 bytes. 20:47:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:20 INFO - trying https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:47:20 INFO - Downloading https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:47:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:47:22 INFO - Downloaded 14413981 bytes. 20:47:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 20:47:22 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')]} 20:47:22 INFO - trying https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:47:22 INFO - Downloading https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:47:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 20:47:25 INFO - Downloaded 111475817 bytes. 20:47:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:47:25 INFO - mkdir: /builds/slave/test/properties 20:47:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:47:25 INFO - Writing to file /builds/slave/test/properties/build_url 20:47:25 INFO - Contents: 20:47:25 INFO - build_url:https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:47:26 INFO - Running post-action listener: _resource_record_post_action 20:47:26 INFO - Running post-action listener: set_extra_try_arguments 20:47:26 INFO - [mozharness: 2016-08-22 03:47:26.179662Z] Finished download-and-extract step (success) 20:47:26 INFO - [mozharness: 2016-08-22 03:47:26.179816Z] Running populate-webroot step. 20:47:26 INFO - Running pre-action listener: _resource_record_pre_action 20:47:26 INFO - Running main action method: populate_webroot 20:47:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:47:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:47:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:47:26 INFO - '--tppagecycles', 20:47:26 INFO - '7'], 20:47:26 INFO - 'tests': ['tsvgm']}}, 20:47:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:47:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:47:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:47:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:47:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:47:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:47:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['damp', 'tps']}, 20:47:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'tests': ['damp', 'tps']}, 20:47:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['dromaeo_dom']}, 20:47:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:47:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['basic_compositor_video']}, 20:47:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:47:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'other-e10s': {'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['a11yr', 20:47:26 INFO - 'ts_paint', 20:47:26 INFO - 'tpaint', 20:47:26 INFO - 'sessionrestore', 20:47:26 INFO - 'sessionrestore_no_auto_restore', 20:47:26 INFO - 'tabpaint']}, 20:47:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['tsvgx', 20:47:26 INFO - 'tsvgr_opacity', 20:47:26 INFO - 'tart', 20:47:26 INFO - 'tscrollx', 20:47:26 INFO - 'cart']}, 20:47:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:47:26 INFO - 'tsvgr_opacity', 20:47:26 INFO - 'tart', 20:47:26 INFO - 'tscrollx', 20:47:26 INFO - 'cart']}, 20:47:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'talos_options': ['--disable-e10s'], 20:47:26 INFO - 'tests': ['tp5o']}, 20:47:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'tests': ['tp5o']}, 20:47:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'talos_options': ['--disable-e10s', 20:47:26 INFO - '--xperf_path', 20:47:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:47:26 INFO - 'tests': ['tp5n']}, 20:47:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:47:26 INFO - 'talos_options': ['--xperf_path', 20:47:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:47:26 INFO - 'tests': ['tp5n']}}, 20:47:26 INFO - 'talos.zip': {'path': '', 20:47:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:47:26 INFO - Running post-action listener: _resource_record_post_action 20:47:26 INFO - [mozharness: 2016-08-22 03:47:26.198412Z] Finished populate-webroot step (success) 20:47:26 INFO - [mozharness: 2016-08-22 03:47:26.198541Z] Running create-virtualenv step. 20:47:26 INFO - Running pre-action listener: _resource_record_pre_action 20:47:26 INFO - Running main action method: create_virtualenv 20:47:26 INFO - Creating virtualenv /builds/slave/test/build/venv 20:47:26 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 20:47:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:47:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:47:26 INFO - Using real prefix '/tools/python27' 20:47:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:47:27 INFO - Installing distribute.............................................................................................................................................................................................done. 20:47:29 INFO - Installing pip.................done. 20:47:29 INFO - Return code: 0 20:47:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:29 INFO - 'HOME': '/Users/cltbld', 20:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:29 INFO - 'LOGNAME': 'cltbld', 20:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:29 INFO - 'MOZ_NO_REMOTE': '1', 20:47:29 INFO - 'NO_EM_RESTART': '1', 20:47:29 INFO - 'PAGER': '/bin/cat', 20:47:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:29 INFO - 'PWD': '/builds/slave/test', 20:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:29 INFO - 'SHELL': '/bin/bash', 20:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:29 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:29 INFO - 'USER': 'cltbld', 20:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:29 INFO - 'XPC_FLAGS': '0x0', 20:47:29 INFO - 'XPC_SERVICE_NAME': '0', 20:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:30 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 20:47:30 INFO - Cleaning up... 20:47:30 INFO - Return code: 0 20:47:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:30 INFO - 'HOME': '/Users/cltbld', 20:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:30 INFO - 'LOGNAME': 'cltbld', 20:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:30 INFO - 'MOZ_NO_REMOTE': '1', 20:47:30 INFO - 'NO_EM_RESTART': '1', 20:47:30 INFO - 'PAGER': '/bin/cat', 20:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:30 INFO - 'PWD': '/builds/slave/test', 20:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:30 INFO - 'SHELL': '/bin/bash', 20:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:30 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:30 INFO - 'USER': 'cltbld', 20:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:30 INFO - 'XPC_FLAGS': '0x0', 20:47:30 INFO - 'XPC_SERVICE_NAME': '0', 20:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:30 INFO - Downloading/unpacking psutil>=3.1.1 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:47:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:47:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:47:33 INFO - Installing collected packages: psutil 20:47:33 INFO - Running setup.py install for psutil 20:47:33 INFO - building 'psutil._psutil_osx' extension 20:47:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:47:34 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 20:47:34 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 20:47:34 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 20:47:34 INFO - building 'psutil._psutil_posix' extension 20:47:34 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 20:47:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:47:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:47:34 INFO - ^ 20:47:34 INFO - 1 warning generated. 20:47:34 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 20:47:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:47:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:47:34 INFO - Successfully installed psutil 20:47:34 INFO - Cleaning up... 20:47:34 INFO - Return code: 0 20:47:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:34 INFO - 'HOME': '/Users/cltbld', 20:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:34 INFO - 'LOGNAME': 'cltbld', 20:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:34 INFO - 'MOZ_NO_REMOTE': '1', 20:47:34 INFO - 'NO_EM_RESTART': '1', 20:47:34 INFO - 'PAGER': '/bin/cat', 20:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:34 INFO - 'PWD': '/builds/slave/test', 20:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:34 INFO - 'SHELL': '/bin/bash', 20:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:34 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:34 INFO - 'USER': 'cltbld', 20:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:34 INFO - 'XPC_FLAGS': '0x0', 20:47:34 INFO - 'XPC_SERVICE_NAME': '0', 20:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:47:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:47:37 INFO - Installing collected packages: mozsystemmonitor 20:47:37 INFO - Running setup.py install for mozsystemmonitor 20:47:37 INFO - Successfully installed mozsystemmonitor 20:47:37 INFO - Cleaning up... 20:47:37 INFO - Return code: 0 20:47:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:47:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:37 INFO - 'HOME': '/Users/cltbld', 20:47:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:37 INFO - 'LOGNAME': 'cltbld', 20:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:37 INFO - 'MOZ_NO_REMOTE': '1', 20:47:37 INFO - 'NO_EM_RESTART': '1', 20:47:37 INFO - 'PAGER': '/bin/cat', 20:47:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:37 INFO - 'PWD': '/builds/slave/test', 20:47:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:37 INFO - 'SHELL': '/bin/bash', 20:47:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:37 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:37 INFO - 'USER': 'cltbld', 20:47:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:37 INFO - 'XPC_FLAGS': '0x0', 20:47:37 INFO - 'XPC_SERVICE_NAME': '0', 20:47:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:37 INFO - Downloading/unpacking jsonschema==2.5.1 20:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:47:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:40 INFO - Downloading functools32-3.2.3-2.tar.gz 20:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:47:40 INFO - warning: no files found matching '*.txt' 20:47:40 INFO - no previously-included directories found matching 'build' 20:47:40 INFO - no previously-included directories found matching 'dist' 20:47:40 INFO - no previously-included directories found matching '.git*' 20:47:40 INFO - Installing collected packages: jsonschema, functools32 20:47:40 INFO - Running setup.py install for functools32 20:47:40 INFO - warning: no files found matching '*.txt' 20:47:40 INFO - no previously-included directories found matching 'build' 20:47:40 INFO - no previously-included directories found matching 'dist' 20:47:40 INFO - no previously-included directories found matching '.git*' 20:47:41 INFO - Successfully installed jsonschema functools32 20:47:41 INFO - Cleaning up... 20:47:41 INFO - Return code: 0 20:47:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:41 INFO - 'HOME': '/Users/cltbld', 20:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:41 INFO - 'LOGNAME': 'cltbld', 20:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:41 INFO - 'MOZ_NO_REMOTE': '1', 20:47:41 INFO - 'NO_EM_RESTART': '1', 20:47:41 INFO - 'PAGER': '/bin/cat', 20:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:41 INFO - 'PWD': '/builds/slave/test', 20:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:41 INFO - 'SHELL': '/bin/bash', 20:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:41 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:41 INFO - 'USER': 'cltbld', 20:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:41 INFO - 'XPC_FLAGS': '0x0', 20:47:41 INFO - 'XPC_SERVICE_NAME': '0', 20:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:47:41 INFO - Cleaning up... 20:47:41 INFO - Return code: 0 20:47:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:41 INFO - 'HOME': '/Users/cltbld', 20:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:41 INFO - 'LOGNAME': 'cltbld', 20:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:41 INFO - 'MOZ_NO_REMOTE': '1', 20:47:41 INFO - 'NO_EM_RESTART': '1', 20:47:41 INFO - 'PAGER': '/bin/cat', 20:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:41 INFO - 'PWD': '/builds/slave/test', 20:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:41 INFO - 'SHELL': '/bin/bash', 20:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:41 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:41 INFO - 'USER': 'cltbld', 20:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:41 INFO - 'XPC_FLAGS': '0x0', 20:47:41 INFO - 'XPC_SERVICE_NAME': '0', 20:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:41 INFO - Downloading/unpacking blobuploader==1.2.4 20:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:44 INFO - Downloading blobuploader-1.2.4.tar.gz 20:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:47:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:47:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:45 INFO - Downloading docopt-0.6.1.tar.gz 20:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:47:45 INFO - Installing collected packages: blobuploader, requests, docopt 20:47:45 INFO - Running setup.py install for blobuploader 20:47:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:47:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:47:45 INFO - Running setup.py install for requests 20:47:45 INFO - Running setup.py install for docopt 20:47:46 INFO - Successfully installed blobuploader requests docopt 20:47:46 INFO - Cleaning up... 20:47:46 INFO - Return code: 0 20:47:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:46 INFO - 'HOME': '/Users/cltbld', 20:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:46 INFO - 'LOGNAME': 'cltbld', 20:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:46 INFO - 'MOZ_NO_REMOTE': '1', 20:47:46 INFO - 'NO_EM_RESTART': '1', 20:47:46 INFO - 'PAGER': '/bin/cat', 20:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:46 INFO - 'PWD': '/builds/slave/test', 20:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:46 INFO - 'SHELL': '/bin/bash', 20:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:46 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:46 INFO - 'USER': 'cltbld', 20:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:46 INFO - 'XPC_FLAGS': '0x0', 20:47:46 INFO - 'XPC_SERVICE_NAME': '0', 20:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Dnw85Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-jXrYda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-LtLr9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-KZ2uPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-cCxWcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:46 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-gazarR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-JSGa4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-SENVFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-zwZeAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-isBClL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-mnjgSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-cmBdo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-bSV2xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-PRB43k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:47 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-PBSyoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:48 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Vsj79h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:48 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-AGUauC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:47:48 INFO - Running setup.py install for manifestparser 20:47:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:47:48 INFO - Running setup.py install for mozcrash 20:47:48 INFO - Running setup.py install for mozdebug 20:47:48 INFO - Running setup.py install for mozdevice 20:47:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:47:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:47:48 INFO - Running setup.py install for mozfile 20:47:48 INFO - Running setup.py install for mozhttpd 20:47:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Running setup.py install for mozinfo 20:47:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Running setup.py install for mozInstall 20:47:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Running setup.py install for mozleak 20:47:49 INFO - Running setup.py install for mozlog 20:47:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Running setup.py install for moznetwork 20:47:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:47:49 INFO - Running setup.py install for mozprocess 20:47:50 INFO - Running setup.py install for mozprofile 20:47:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:47:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:47:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:47:50 INFO - Running setup.py install for mozrunner 20:47:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:47:50 INFO - Running setup.py install for mozscreenshot 20:47:50 INFO - Running setup.py install for moztest 20:47:50 INFO - Running setup.py install for mozversion 20:47:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:47:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:47:50 INFO - Cleaning up... 20:47:50 INFO - Return code: 0 20:47:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:50 INFO - 'HOME': '/Users/cltbld', 20:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:50 INFO - 'LOGNAME': 'cltbld', 20:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:50 INFO - 'MOZ_NO_REMOTE': '1', 20:47:50 INFO - 'NO_EM_RESTART': '1', 20:47:50 INFO - 'PAGER': '/bin/cat', 20:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:50 INFO - 'PWD': '/builds/slave/test', 20:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:50 INFO - 'SHELL': '/bin/bash', 20:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:50 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:50 INFO - 'USER': 'cltbld', 20:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:50 INFO - 'XPC_FLAGS': '0x0', 20:47:50 INFO - 'XPC_SERVICE_NAME': '0', 20:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-zpUpu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-equeTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-E8z30R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Hx5ncA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-QUjHlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-5cSUxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-VgGRzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Ej1hHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:51 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)) 20:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:51 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-aWCE9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4LgElL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-14mg5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-jF0cRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-1iQfGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-JxWm1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-ZFegUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-t8IpuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:52 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4MSbXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:55 INFO - Downloading blessings-1.6.tar.gz 20:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:47:55 INFO - Installing collected packages: blessings 20:47:55 INFO - Running setup.py install for blessings 20:47:55 INFO - Successfully installed blessings 20:47:55 INFO - Cleaning up... 20:47:55 INFO - Return code: 0 20:47:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:47:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:55 INFO - Reading from file tmpfile_stdout 20:47:55 INFO - Current package versions: 20:47:55 INFO - blessings == 1.6 20:47:55 INFO - blobuploader == 1.2.4 20:47:55 INFO - docopt == 0.6.1 20:47:55 INFO - functools32 == 3.2.3-2 20:47:55 INFO - jsonschema == 2.5.1 20:47:55 INFO - manifestparser == 1.1 20:47:55 INFO - mozInstall == 1.12 20:47:55 INFO - mozcrash == 0.17 20:47:55 INFO - mozdebug == 0.1 20:47:55 INFO - mozdevice == 0.48 20:47:55 INFO - mozfile == 1.2 20:47:55 INFO - mozhttpd == 0.7 20:47:55 INFO - mozinfo == 0.9 20:47:55 INFO - mozleak == 0.1 20:47:55 INFO - mozlog == 3.3 20:47:55 INFO - moznetwork == 0.27 20:47:55 INFO - mozprocess == 0.23 20:47:55 INFO - mozprofile == 0.28 20:47:55 INFO - mozrunner == 6.12 20:47:55 INFO - mozscreenshot == 0.1 20:47:55 INFO - mozsystemmonitor == 0.3 20:47:55 INFO - moztest == 0.7 20:47:55 INFO - mozversion == 1.4 20:47:55 INFO - psutil == 3.1.1 20:47:55 INFO - requests == 1.2.3 20:47:55 INFO - wsgiref == 0.1.2 20:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47: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')]} 20:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:56 INFO - 'HOME': '/Users/cltbld', 20:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:56 INFO - 'LOGNAME': 'cltbld', 20:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:56 INFO - 'MOZ_NO_REMOTE': '1', 20:47:56 INFO - 'NO_EM_RESTART': '1', 20:47:56 INFO - 'PAGER': '/bin/cat', 20:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:56 INFO - 'PWD': '/builds/slave/test', 20:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:56 INFO - 'SHELL': '/bin/bash', 20:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:56 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:56 INFO - 'USER': 'cltbld', 20:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:56 INFO - 'XPC_FLAGS': '0x0', 20:47:56 INFO - 'XPC_SERVICE_NAME': '0', 20:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:56 INFO - Cleaning up... 20:47:56 INFO - Return code: 0 20:47:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10efbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f053618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9349d907e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, '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.VyW1HpRpE2/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.wUq5uyX11X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 20:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:56 INFO - 'HOME': '/Users/cltbld', 20:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:56 INFO - 'LOGNAME': 'cltbld', 20:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:56 INFO - 'MOZ_NO_REMOTE': '1', 20:47:56 INFO - 'NO_EM_RESTART': '1', 20:47:56 INFO - 'PAGER': '/bin/cat', 20:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:56 INFO - 'PWD': '/builds/slave/test', 20:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:56 INFO - 'SHELL': '/bin/bash', 20:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:47:56 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:56 INFO - 'USER': 'cltbld', 20:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:56 INFO - 'XPC_FLAGS': '0x0', 20:47:56 INFO - 'XPC_SERVICE_NAME': '0', 20:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:47:56 INFO - Cleaning up... 20:47:56 INFO - Return code: 0 20:47:56 INFO - Running post-action listener: _resource_record_post_action 20:47:56 INFO - Running post-action listener: _start_resource_monitoring 20:47:56 INFO - Starting resource monitoring. 20:47:56 INFO - [mozharness: 2016-08-22 03:47:56.918091Z] Finished create-virtualenv step (success) 20:47:56 INFO - [mozharness: 2016-08-22 03:47:56.919049Z] Running install step. 20:47:56 INFO - Running pre-action listener: _resource_record_pre_action 20:47:56 INFO - Running main action method: install 20:47:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:57 INFO - Reading from file tmpfile_stdout 20:47:57 INFO - Detecting whether we're running mozinstall >=1.0... 20:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:47:57 INFO - Reading from file tmpfile_stdout 20:47:57 INFO - Output received: 20:47:57 INFO - Usage: mozinstall [options] installer 20:47:57 INFO - Options: 20:47:57 INFO - -h, --help show this help message and exit 20:47:57 INFO - -d DEST, --destination=DEST 20:47:57 INFO - Directory to install application into. [default: 20:47:57 INFO - "/builds/slave/test"] 20:47:57 INFO - --app=APP Application being installed. [default: firefox] 20:47:57 INFO - mkdir: /builds/slave/test/build/application 20:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:48:31 INFO - Reading from file tmpfile_stdout 20:48:31 INFO - Output received: 20:48:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:48:31 INFO - Running post-action listener: _resource_record_post_action 20:48:31 INFO - [mozharness: 2016-08-22 03:48:31.074002Z] Finished install step (success) 20:48:31 INFO - [mozharness: 2016-08-22 03:48:31.074145Z] Running run-tests step. 20:48:31 INFO - Running pre-action listener: _resource_record_pre_action 20:48:31 INFO - Running main action method: run_tests 20:48:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:48:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:48:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:48:31 INFO - 2016-08-21 20:48:31.088 screenresolution[1631:7552] starting screenresolution argv=screenresolution get 20:48:31 INFO - 2016-08-21 20:48:31.091 screenresolution[1631:7552] Display 0: 1600x1200x32@60 20:48:31 INFO - 2016-08-21 20:48:31.096 screenresolution[1632:7554] starting screenresolution argv=screenresolution list 20:48:31 INFO - Available Modes on Display 0 20:48:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:48:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:48:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:48:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:48:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:48:31 INFO - Intel Iris: 20:48:31 INFO - Chipset Model: Intel Iris 20:48:31 INFO - Type: GPU 20:48:31 INFO - Bus: Built-In 20:48:31 INFO - VRAM (Dynamic, Max): 1536 MB 20:48:31 INFO - Vendor: Intel (0x8086) 20:48:31 INFO - Device ID: 0x0a2e 20:48:31 INFO - Revision ID: 0x0009 20:48:31 INFO - Displays: 20:48:31 INFO - Display: 20:48:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:48:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:48:31 INFO - Display Serial Number: 1600x1200 60 20:48:31 INFO - Main Display: Yes 20:48:31 INFO - Mirror: Off 20:48:31 INFO - Online: Yes 20:48:31 INFO - Rotation: Supported 20:48:31 INFO - Return code: 0 20:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:48:31 INFO - Python 2.7.3 20:48:31 INFO - Return code: 0 20:48:31 INFO - grabbing minidump binary from tooltool 20:48: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')]} 20:48:31 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 0x10ef22d30>, '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 0x10ec87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef1ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:48:31 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 20:48:31 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 20:48:31 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 20:48:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:48:31 INFO - Return code: 0 20:48:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:48:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:48:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:48:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:48:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0149 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VyW1HpRpE2/Render', 20:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:48:31 INFO - 'HOME': '/Users/cltbld', 20:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:48:31 INFO - 'LOGNAME': 'cltbld', 20:48:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:48:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:48:31 INFO - 'MOZ_NO_REMOTE': '1', 20:48:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:48:31 INFO - 'NO_EM_RESTART': '1', 20:48:31 INFO - 'PAGER': '/bin/cat', 20:48:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:48:31 INFO - 'PWD': '/builds/slave/test', 20:48:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:48:31 INFO - 'SHELL': '/bin/bash', 20:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wUq5uyX11X/Listeners', 20:48:31 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 20:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:48:31 INFO - 'USER': 'cltbld', 20:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:48:31 INFO - 'XPC_FLAGS': '0x0', 20:48:31 INFO - 'XPC_SERVICE_NAME': '0', 20:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:48:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:48:31 INFO - mozversion application_buildid: 20160821184714 20:48:31 INFO - mozversion application_changeset: 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 20:48:31 INFO - mozversion application_display_name: Nightly 20:48:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:48:31 INFO - mozversion application_name: Firefox 20:48:31 INFO - mozversion application_remotingname: firefox 20:48:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:48:31 INFO - mozversion application_vendor: Mozilla 20:48:31 INFO - mozversion application_version: 51.0a1 20:48:31 INFO - mozversion platform_buildid: 20160821184714 20:48:31 INFO - mozversion platform_changeset: 64f4ea57b6fde8ed315f5bc8c9caefe389bb8923 20:48:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:48:31 INFO - mozversion platform_version: 51.0a1 20:48:31 INFO - using testdate: 1471837711 20:48:31 INFO - actual date: 1471837711 20:48:31 INFO - starting webserver on 'localhost:49263' 20:48:31 INFO - SUITE-START | Running 2 tests 20:48:31 INFO - TEST-START | dromaeo_css 20:48:31 INFO - operating with platform_type : mac_ 20:48:31 INFO - Initialising browser for dromaeo_css test... 20:48:31 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpsZiIOi/profile http://localhost:49263/getInfo.html) 20:48:33 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 20:48:33 INFO - PROCESS | 1642 | colorDepth:24 20:48:33 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 20:48:33 INFO - PROCESS | 1642 | __metrics 20:48:34 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:48:34 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:48:34 INFO - TEST-INFO | 1642: exit 0 20:48:34 INFO - Browser initialized. 20:48:34 INFO - Running cycle 1/1 for dromaeo_css test... 20:48:34 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpsZiIOi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:48:46 INFO - PROCESS | 1644 | RSS: Main: 228638720 20:48:46 INFO - PROCESS | 1644 | 20:49:38 INFO - PROCESS | 1644 | 1471837778081 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:51:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 20:51:49 INFO - PROCESS | 1644 | RSS: Main: 319844352 20:51:49 INFO - PROCESS | 1644 | 20:54:52 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 20:54:53 INFO - PROCESS | 1644 | RSS: Main: 367108096 20:54:53 INFO - PROCESS | 1644 | 20:57:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 20:57:56 INFO - PROCESS | 1644 | RSS: Main: 347828224 20:57:56 INFO - PROCESS | 1644 | 21:00:58 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 21:00:59 INFO - PROCESS | 1644 | RSS: Main: 337469440 21:00:59 INFO - PROCESS | 1644 | 21:04:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 21:04:03 INFO - PROCESS | 1644 | RSS: Main: 336666624 21:04:03 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 21:07:07 INFO - PROCESS | 1644 | RSS: Main: 442441728 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | __start_tp_report 21:07:07 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 21:07:07 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 21:07:07 INFO - PROCESS | 1644 | |i|pagename|runs| 21:07:07 INFO - PROCESS | 1644 | |0;dojo.html;2930.428566429546;3053.3282677810876;3092.5670406143145;3093.7060979206976;3107.6736942621023;5483.999999999995;5606.663600183992;5672.602917795756;5694.2597462329895;5792.884142317156;5647.632903861257;5695.544356451484;5701.6864072476;5717.857053573663;5723.084306510959;5150.639455238135;5189.273501709764;5212.400573934008;5222.981518603855;5281.339832520935;5221.2429197766305;5326.786928522854;5333.933213357344;5334.813281535147;5339.5461385782255;4163.437935878648;5123.129068058421;5355.036093503168;5366.436524164965;5416.727069138749;4339.93671550469;5114.565261952738;5263.6578622389425;5274.235235890794;5296.126139187029;5084.288199652052;5151.639385243035;5190.429052804188;5235.502627250406;5259.184826351921;5592.328920529562;5699.430056994309;5726.685032323262;5730.36965933747;5746.109353050284;5568.9443105569235;5890.587658863881;6244.750209991595;6253.249610046778;6260.561760676755;6198.876022479571;6245.375462453764;6250.562460627758;6277.591956522857;6316.05259211122;5780.624259423124;5780.826575202708;5790.36306006339;5795.88408231833;5795.9420405796245;6244.93836047873;6312.4634406076;6312.873742525124;6317.3366796486625;6328.303886572473;5925.970370148121;6065.575409721366;6083.939160608337;6156.8768624627255;6172.876542469216;6228.532860035515;6240.219972503437;6278.717457714414;6289.119523266746;6295.96852015737;5969.403059693996;6215.409536094064;6227;6229.626222426669;6256.186695729617;5899.439553242435;6069.787557435557;6073;6168.259808822881;6191.566590338633;4903.41159060915;4930.556249937523;4938.209886418156;4943.110240156806;4943.307936888838;4039.414284928669;4199.895002624898;4219.029623186623;4222.767747773902;4227.154569086195;5601.047821870292;5666.178404131378;5671.064274394761;5728.684922329228;5731.5701322400155;6317.210348706411;6321.620702757849;6334.619922804554;6337.6197428154455;6359.8092057238355;5521.365043020104;5699.2590963174525;5703.885922281531;5720.771169153188;5721.313442386857;5359.383670877821;5412.431694672082;5420.295361602966;5479.424660410679;5479.643823151482;5518.91721624176;6257.2804127525005;6336.461400780985;6363.745450181941;6365.586236894587;6157.983932651242;6360.522960777867;6372.203474565679;6378.489720822416;6386.201724784402;6271.059341098871;6300.338464461349;6340.175777148941;6390;6395.1046853441485;5979.501991335118;6014.398560144125;6032.366601506778;6039.094135879477;6040.969795150996;5930.555208359304;6012.669303188367;6181.660008699565;6197.25632924052;6213.9067913980425;5786.428721310286;5788.160716696139;5838.153467747238;5926.2295901532525;5929.584929054925;5890.293164820249;5913.379095194942;5919.23050003514;5919.526437884872;5924.644521328734;5559.305086864142;5713.228714123707;5763.596548241583;5763.596548241583;5767.192593036894;5046.8644554974835;5747.08046712524;5771;5858.121281807763;5859.619124756877;5642.971785141048;5742;5751.367349591458;5784.566157538286;5787.508061814625 21:07:07 INFO - PROCESS | 1644 | |1;ext.html;484.87150905010174;508.0753029486334;512.9025485157822;514.7220500929499;558.4865015807168;29555;38435.23129537408;39429.40855887157;39508.814735557906;40929.79535102328;36010.27979440416;41045.38431923516;41149.58850411495;41175.58824411755;41213.17573648525;9638.084381983717;9833.213342932548;9835.852462213037;9835.95082024589;9845.507724613777;8795.956020219923;9013.504257265848;9896.455694936776;11474.737778844363;12092.395380230997;10779.5688172473;12006.459709313101;12043.397830108503;12047.759044819099;12058.69853253667;11948.283103013804;12005.339706316148;12042;12055.517779288817;12063.974562162226;11856.644300670994;11932.926036656656;11937.761244775143;11942.343171125585;11953.760924781544;39144.02139946495;39411.21177576432;39433.60566394328;39456.01359965995;39457.80271098655;3005.7144571265912;3557.9326202139514;3701.907452313687;3702.4446333049987;4129.463169787939;30866.84566577153;30879.536806947915;30902;30961;31061.378772424425;3230.2731885325848;3318.2533929865585;3342.7660063795543;3398.3543126805826;3402.030421329909;512388;513791;514620.4268978669;515040;521710;55006.77435643607;55343;55436;55455.89088218213;55490.72254638701;58327;58338.7083064582;58385.416145838;58456.830863383344;58513.70743146257;16124.435644752377;16316.184190790413;16343.019418834909;16360.918195408947;16396.508104756875;11570;11746.29522228669;12819.038572107129;12876.935615321863;12911.902488288413;14461.78307325391;14798.408063677332;14803.0378025428;15224.923875380553;15252.618684532754;5555.916661250085;6589.406953374123;6631.535792494576;6649.268580456146;6651.700673469705;917.0096296000304;924.5793164110314;950.3205208275978;960.9621608662627;1118.669992352258;28221.15336539907;29686.55470167949;29743.85128074389;29773.851130744206;30468.84765576158;946.1720994130136;1015.7562185075676;1026.6047571684805;1095.7917995580815;1107.3854011023798;14681.192534410497;15279.923600381928;15339.61650958735;15430.919835611623;15779.68440631181;629652;635744;636730;637178;637297;48798.51201488011;55380;56021.71989140028;56228;60088.098678519855;1357.2263809628416;1369.205860600849;1405.6766943602825;1408.4577437686564;1410.8659677330843;26079.044981453462;30056.46676534659;30897.91857284984;31059.640880678642;31378.843105784326;47996.28005579989;48549.5145048545;48568;48591.514084858696;48671.75664121657;537541;543389;544208;544923;545593;37800;38342.84971450861;38500.22999540049;38595.614043859205;38603.420948686355;47087.764561178345;47535.28697069616;47755.52244477511;48070.79823004313;48177.75911120422;38539;38646.033849153995;39005.80497097496;39924.80037599793;40261.19477610373;37655;38465.2306953865;39990.200195995334;40001.79999099986;42306.7884660587;38544.6145538541;40824.1835163301;41473.1705365897;41827.58172418237;42392.57607423886;33897.49153762745;39862.800685996386;41216.587834122474;42860.92847678833;42909.42177516903;25740.61389079203;31405.842970785;32087.358252835278;32896.1775955603;32925.67074329226 21:07:07 INFO - PROCESS | 1644 | |2;jquery.html;355.20965850981554;356.2181012677173;371.22043708212755;374.31687170913074;406.5934065934066;15872.365105395786;15884;20894;21005.264815731432;21038.368848934555;20693.793062069373;20713.63019711117;21174.470638234052;21211.939403029864;21263.787362126375;5931.288245410546;6049.062395328731;6055.5761096723245;6139.846503837395;6203.2245969253845;6021.72902219399;6133.693315334238;6137.110119032737;6137.785177518787;6150.6924653767355;4231.894202644928;6364.363563643645;6769.356911093439;6829.351211634887;6914.135733033371;6555.901661475082;6739.427148692367;6814.48891333152;6845.657717114149;6874.450043996493;5870.823875283706;5878.118282257696;5878.5297176225795;5886.470217680387;5999.76000959961;21902.45243868916;26609.866950665124;27001;27348.906043758227;27610;2330.860148391102;2376.881155942196;2428.550718117154;3175.523671449278;3295.340931813623;13542.796858047039;15355.923220383938;15770.448034318744;16723.91638041802;16800.495985120466;1850.5928695686928;1912.2159914435153;1953.433504283756;1954.0718158874563;1955.7555305586802;123160.38419807934;126972.36513817465;127451.7254827449;129107;129772.05341919878;7945.364370850318;9600.471974041355;10941.96051375118;11050.889491105147;11184;13843;13848.653783655489;13852.376643051088;13863.168209907439;13868.306584670727;8608.139186081342;8710.520921349387;8769.693060742844;8799.164079412445;8860.512671803113;8821.338399620054;8830.690925817566;8835.69075082369;9019.052999435096;9047.095290470901;6557.803265902031;6646.86706265872;6675.399214070757;6692.122387373476;6828.658567071627;1569.591906104421;1778.6087060846594;1813.7823461184742;1818.2272534173187;1822.3712819077398;643.8905386084377;806.9636866341028;949.520492151459;1025.7435641089728;1026.1585499890018;21879;22260.22089226869;22573.88713056457;22766.31701048971;22766.544669106526;872;957.818014577228;969.5879251318162;989.2926557511414;1025.9487025648837;9378.85931711025;11346.716332091599;11441.31352118859;11470.54117835294;11537;579632;610538;613589;614815;636303;71041.2895871045;71194.2880571198;104645.47677261564;116762;116778;976.3312131190157;1036.461040259061;1044.3785947361307;1069.9197560183018;1070.4594179939081;17898.010446154625;18854.434366969013;19003.334883279018;19298;19321.323753668556;42163.459135216195;48463.66899641107;54210.186847198114;59545.702271488364;61408;80765.19234807577;80797.59601201957;81055.59472202601;81843.5907820457;82033.58983205046;6735.932640673726;7613.771586852404;8068.273855353046;8242.546659933761;8309.792255193668;10632.41521716313;11846.407679615812;12536.247825130522;13160;13224.206547607175;9068.773280667772;10912.890871091187;11108.778034416046;11196.552137914392;11257.324560526395;10263.846042309222;11137.220394572301;11402.94298528502;11427.371494567878;11749;11284.661460156209;11364;11658.551704142224;11799.233049851731;11835.230710003822;8119.106898241307;8179.386546009192;8181.036551227781;8204.810302506223;8274;9167.49578773174;11213.439328033402;11406.600768973047;11519.020883224684;11549.018333441416 21:07:07 INFO - PROCESS | 1644 | |3;mootools.html;1454.461849115827;1541.9074855508675;1723.8189990051044;1884.7738271407434;1890.9338173163942;8335.874961875577;8847.73456796297;8872.512011839355;8872.955635221831;8883.866741998876;6801.387875091241;7835.451518393715;8059.476134051282;8562.871556926651;8704.216620504154;2336.696229490168;2392.461696118377;2394.3295877154424;2399.3042017814814;2400.6159014557675;2361.126383238204;2387.4747555537756;2420.491696743686;2421.406755344943;2422.0917156066475;2110.6517424624917;2195;2325.4186453386656;2400.9519809603885;2402.039184326266;2366.8461549999197;2372.3950392649936;2397.352714767012;2400.543896659629;2401.8438801477846;2379.6906402167606;2380.357303528055;2383.833131680783;2387.7015373078366;2389.187676190086;8609.009963854174;8655.35084868631;8655.783605409852;8663.48019118855;8674;4203.369494575808;5177.818776342848;5347.25138480613;5347.304850369427;5350.170723537858;3460.1522626956557;3509.701675357572;3644.2893635741043;3653.707703383723;3662.285854258421;1073.8335557988523;1114;1114.7380365614074;1136.0911270983263;1143.6283207957447;11014.118870490342;11046.944765276203;11155.38645374504;11182.944085279521;11195.440227988649;4524.2082635538645;4622.722636641813;4794.5924596409695;4797.784099715521;4816.518348165156;9841.163501102343;9845.064718851698;9849.50752462374;9852.113309802153;9859.802803943881;5252.527272545489;5346.625736198506;5350.839474815762;5354.946450535523;5355.919661205085;4711.387519622427;4862.343583616237;4885.5847252983895;4886.218205087169;4893.437254715754;3757.3236817373136;3792.146766977408;3822.369309064029;3851.6340947609933;3876.441548577925;1007.6573964851985;1103.6358001859367;1186.0511590727383;1189.4350183662864;1199.5904811846046;679.8572299816994;749.8425330680509;835.4068611285934;842.8103676672822;930.2232635749225;5707.1153971134545;5744.712764361765;5898.292204935436;5991.430814072656;6155.72299246535;838.5262326785347;914.3325372478129;945.4610871803006;967.2455484721928;968.0754879090451;3553.4847447120023;3632.582253040934;3878.825452854628;3941.3102707026155;4027.9194416111513;10340.224483163793;10625.937406259312;10640.148788096934;10644.201684873666;10652.520636571373;10518.579256829642;10600.787984240273;10637.574497020032;10640.946795265974;10648.520816563274;7549.622518874145;7984.241497057887;8083.434159608771;8092.797680057928;8104.432689711663;4817.975910120427;5848.766049357978;6007.3992600739575;6047.758089676364;6112.877742445037;8183.8772418414965;9365.85951210746;9759.658411955548;9837.360571562825;9976.451295178835;9311.534423278945;9901.900980990386;9954.402735835873;10015.69952901414;10065.899341006496;7488.737894173678;7606.353459955966;7608;7609.581473019037;7613.619319034137;7700.075990881131;7934.246246606564;8051.718189863327;8120.67517299325;8191.385646076687;6047.697615119315;6165.907511387422;7365.631718414165;7491.550506969599;7510.173880873209;7177.712891484282;7273.418126549757;7275.599842008741;7329.083864516935;7443.218462061622;6899.275576064641;6913.3777959983845;6982.650867456506;6982.860342793014;6995.265497122728;4884.584810291023;4986.202207646759;5025.195968644999;5060;5182.714950677749;5023.547880690755;5028.296038554532;5086.64393492452;5242.76407561653;5242.97378513105 21:07:07 INFO - PROCESS | 1644 | |4;prototype.html;291.728692316146;302.4314289136423;304.03317450507393;308.877993192689;310.706382468567;4380.540043295456;4565.5206203348625;4789.778606455353;5069.974650126745;5313.734313284335;4371.2350338690685;4418.094290670413;4550.954490455095;4936.469694394731;5288.206768984644;3080.522519009557;3845.538535375759;3904.941425878614;4009.87723437438;4031.3549832026883;3832.2335532893535;4027.8993025174314;4028.5769994150633;4055.817488213023;4082.163156552914;3454.5509083819193;3572.92854142917;3809.942850857239;3996.081880697267;4036.757794532323;3755.455458958436;3781.6953151162943;3912.3544615138467;3923.195744872294;4057.797110144496;2883.7692984561186;2883.8320480205552;3224.0172294039;3472.8641885240395;3511.3328467591077;4874.53691899269;5096.490350964911;5173.456787037345;5193.221016847466;5239.397469291004;4214.64175545076;4440.289553671397;4499.842505512292;4534.546545345439;4568.0407114506;5472.34331880173;5483.424240454775;5489.972550137264;5494.36814766303;5537.612367134302;3852.537695476533;3853.863110382444;3860.6718428933295;3874.593167717377;3880.2627500775043;4979.601631869442;5129.897402051975;5245.901639344293;5901.196931457566;5930.258717660293;3162.826044567525;3629.637036296402;3760.811959402019;3781.9810900945317;3788.5264341957254;3827.598102199229;3840.7695538267794;3847.942280865789;3851.2105018471148;3852.768833869921;4505.256632655641;4505.797239124247;4507.414036175342;4511.548845115528;4638.768061596907;5363.594117735266;5401.459854014645;5453;5502.669839809624;5543.196236545678;1097.8023955687893;1134.9728495711395;1284.6081945006677;1303.5372442782675;1308.358904136985;761.6458346868621;789.9921000790033;806.2260230178977;815.7267315449272;818.6970820796344;4377.409049778239;5014.774335154927;5041.6470847040355;5045.360257916192;5058.519440653132;5526.640768350045;5716.942830571724;5735.913961290584;5745.626534275342;5752.6836024019085;4347.391365208873;4849.612031037579;4953.083679519301;5201.269333599815;5218.243354713545;2949.336399310181;2992.835394053348;2998.625171853518;3006.6920889413177;3063.249503871521;4776.280539005955;4974.427940786856;4997.5002499749735;5436.483534064337;5957.314908785545;4902.313676085351;5122.208396423884;5375.6774593524515;5810.360860305363;5853.795117170878;5925.05199168131;5949.940500595025;5954.851128721816;5956.52347812174;5958.582899197015;4579.313103034571;4943.382077240345;5470.839018587715;5582.525485333792;5682.659040457585;5226.712530810842;5509.5041446270025;5591.664500130005;5597.748101335531;5614.989301925692;4763.642726795435;4951.10880041596;5164.535191832603;5780.682062486603;5902.455109565696;3455.3911796594416;3710.2579484103826;3752.080740218664;3763.6048214938132;3781.77092444951;4776.283557466408;5408.323959505062;5580.330360356783;5589.692566908859;5606.990741666539;4868.318435419115;4981.900361992813;5006.424261210008;5404.891902161856;5484.533814625642;5055.595552355876;5062.594992400673;5389.434109418455;5403.352357344951;5417.945820541901;5326.493983071602;5331.493508116722;5332.73336333174;5336.9466305338;5339.650553756334;4749.976250118865;5020.698758074527;5190.870228244324;5337.279467271868;5343.145096784496;4810.302506136661;4931.753412329441;5233.371995360429;5286.286351342674;5402.297701298806 21:07:07 INFO - PROCESS | 1644 | |5;yui.html;730.7551970090019;767.4972892755244;787.3425689549226;817.1929422433936;850.1201256699316;14980.026298290604;15924.920375398136;15925.124118173495;15947;15959.601009974756;14370.784438233408;14546.199959002251;14559;14567.708645827104;14571.27143642819;660.696079803291;676.780046484892;703.989440158398;836.5357226739172;861.6424183963647;846.3440833354139;864.2006144316482;866.6879923227633;867.5185272173941;867.8047439326165;919.1176470588243;923.6674797073048;924.3160061554469;925.1211349218236;927.8541001862694;715;832.8334333133398;919.1819280840058;921.2537844346068;925.0841666749869;681.1247546902207;681.2029924987776;681.7204945972128;683.1051322767191;684.2815044203567;14506.927465362605;14523.564293071226;14542.981991260616;14545.127292362386;14556.636084097876;11403.885961140366;14159.22124283164;14272;14302.57092287233;14305.14169149844;12994.740105197938;13055.608331750063;13112.541061062913;13260.801087983687;13326.600201993955;4596.080783843245;4618.8152473901;4709.387779588632;4838.588719958808;4839.879003024917;118863;120037.38953093148;124595.3770231143;124731.02151827831;130941;11268.436578171058;11367.715807104889;11431.485583148778;11452.198346115692;11601.12991525622;23502.412439688804;23525;23543.41141471443;23557.764422355558;23578.05687772504;7679.500832445873;7734.187910269453;8070.394720395994;8220.424570280024;8228.670853165926;4483.686141970031;4486.461624605068;4491.214037543482;4538.546145385501;4571.634269258451;3474.9305013900084;3609.5668519777796;3629.94555081674;3630.43728222126;3632.400653892078;567.1945836911596;572.1189368372743;576.4984463516683;579.3800633322389;585.648610833505;457.9816807327736;584.1588113117098;625.2434554189482;646.9902951455732;710.9360157585842;8398.286145677686;9726.221902247804;9861.605535778488;9884.209263258923;9919.057689519484;612.3845535237058;660.2440205964144;672.8586996730644;680.6290571638469;683.9384455398938;4497.280435130363;4562.130646888491;4796.5203479651755;4805.903881922342;4838.250071238964;101407;142578.28710856586;158782;163417;164902;101507;120944;136392;142184;144535.83196252063;117050;123826.38086809509;123907;124787.02555693375;127338.81652958565;8716.38985271025;8845.292376609726;8884.911150888667;8901.821963560822;9080.636774528944;61809.69095154495;63267.367326326144;63489.68255158695;64423.35576644361;64519;93455;96778.54832177665;97614.02385976049;98314;98995;11778.882211178121;12100.757984840078;12156.513739450324;12224.816627750768;12401;13408.597742067754;13560.321983900569;13687.520936767563;13807.585772426843;14091.807250449501;12386.75226495483;12549.560765373168;12621.558245461365;12692.984561235264;12704.480575896736;11677.591284305008;11911.987481064207;11989.460474278503;12089.335086570323;12569.183003104768;12065.758684826429;12280.263184208976;12540.31028293416;12620.305883176514;12824.935875320563;8866.42368246062;9147.496887671243;9403.764905877408;9412.905870941204;9540.284478664267;8275;9256.444613323221;9637.469939153414;9761.658341957997;9802.558884850056 21:07:07 INFO - PROCESS | 1644 | __end_tp_report 21:07:07 INFO - PROCESS | 1644 | __start_cc_report 21:07:07 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1366 21:07:07 INFO - PROCESS | 1644 | __end_cc_report 21:07:07 INFO - PROCESS | 1644 | __startTimestamp1471838827352__endTimestamp 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | ------- Summary: start ------- 21:07:07 INFO - PROCESS | 1644 | Number of tests: 6 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5667.54 Median:5780.62 stddev:688.19 (11.9%) stddev-sans-first:655.95 21:07:07 INFO - PROCESS | 1644 | Values: 2930.4 3053.3 3092.6 3093.7 3107.7 5484.0 5606.7 5672.6 5694.3 5792.9 5647.6 5695.5 5701.7 5717.9 5723.1 5150.6 5189.3 5212.4 5223.0 5281.3 5221.2 5326.8 5333.9 5334.8 5339.5 4163.4 5123.1 5355.0 5366.4 5416.7 4339.9 5114.6 5263.7 5274.2 5296.1 5084.3 5151.6 5190.4 5235.5 5259.2 5592.3 5699.4 5726.7 5730.4 5746.1 5568.9 5890.6 6244.8 6253.2 6260.6 6198.9 6245.4 6250.6 6277.6 6316.1 5780.6 5780.8 5790.4 5795.9 5795.9 6244.9 6312.5 6312.9 6317.3 6328.3 5926.0 6065.6 6083.9 6156.9 6172.9 6228.5 6240.2 6278.7 6289.1 6296.0 5969.4 6215.4 6227.0 6229.6 6256.2 5899.4 6069.8 6073.0 6168.3 6191.6 4903.4 4930.6 4938.2 4943.1 4943.3 4039.4 4199.9 4219.0 4222.8 4227.2 5601.0 5666.2 5671.1 5728.7 5731.6 6317.2 6321.6 6334.6 6337.6 6359.8 5521.4 5699.3 5703.9 5720.8 5721.3 5359.4 5412.4 5420.3 5479.4 5479.6 5518.9 6257.3 6336.5 6363.7 6365.6 6158.0 6360.5 6372.2 6378.5 6386.2 6271.1 6300.3 6340.2 6390.0 6395.1 5979.5 6014.4 6032.4 6039.1 6041.0 5930.6 6012.7 6181.7 6197.3 6213.9 5786.4 5788.2 5838.2 5926.2 5929.6 5890.3 5913.4 5919.2 5919.5 5924.6 5559.3 5713.2 5763.6 5763.6 5767.2 5046.9 5747.1 5771.0 5858.1 5859.6 5643.0 5742.0 5751.4 5784.6 5787.5 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:70227.16 Median:39074.91 stddev:151232.72 (387.0%) stddev-sans-first:151566.32 21:07:07 INFO - PROCESS | 1644 | Values: 484.9 508.1 512.9 514.7 558.5 29555.0 38435.2 39429.4 39508.8 40929.8 36010.3 41045.4 41149.6 41175.6 41213.2 9638.1 9833.2 9835.9 9836.0 9845.5 8796.0 9013.5 9896.5 11474.7 12092.4 10779.6 12006.5 12043.4 12047.8 12058.7 11948.3 12005.3 12042.0 12055.5 12064.0 11856.6 11932.9 11937.8 11942.3 11953.8 39144.0 39411.2 39433.6 39456.0 39457.8 3005.7 3557.9 3701.9 3702.4 4129.5 30866.8 30879.5 30902.0 30961.0 31061.4 3230.3 3318.3 3342.8 3398.4 3402.0 512388.0 513791.0 514620.4 515040.0 521710.0 55006.8 55343.0 55436.0 55455.9 55490.7 58327.0 58338.7 58385.4 58456.8 58513.7 16124.4 16316.2 16343.0 16360.9 16396.5 11570.0 11746.3 12819.0 12876.9 12911.9 14461.8 14798.4 14803.0 15224.9 15252.6 5555.9 6589.4 6631.5 6649.3 6651.7 917.0 924.6 950.3 961.0 1118.7 28221.2 29686.6 29743.9 29773.9 30468.8 946.2 1015.8 1026.6 1095.8 1107.4 14681.2 15279.9 15339.6 15430.9 15779.7 629652.0 635744.0 636730.0 637178.0 637297.0 48798.5 55380.0 56021.7 56228.0 60088.1 1357.2 1369.2 1405.7 1408.5 1410.9 26079.0 30056.5 30897.9 31059.6 31378.8 47996.3 48549.5 48568.0 48591.5 48671.8 537541.0 543389.0 544208.0 544923.0 545593.0 37800.0 38342.8 38500.2 38595.6 38603.4 47087.8 47535.3 47755.5 48070.8 48177.8 38539.0 38646.0 39005.8 39924.8 40261.2 37655.0 38465.2 39990.2 40001.8 42306.8 38544.6 40824.2 41473.2 41827.6 42392.6 33897.5 39862.8 41216.6 42860.9 42909.4 25740.6 31405.8 32087.4 32896.2 32925.7 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35129.94 Median:27174.95 stddev:101491.77 (373.5%) stddev-sans-first:101742.90 21:07:07 INFO - PROCESS | 1644 | Values: 355.2 356.2 371.2 374.3 406.6 15872.4 15884.0 20894.0 21005.3 21038.4 20693.8 20713.6 21174.5 21211.9 21263.8 5931.3 6049.1 6055.6 6139.8 6203.2 6021.7 6133.7 6137.1 6137.8 6150.7 4231.9 6364.4 6769.4 6829.4 6914.1 6555.9 6739.4 6814.5 6845.7 6874.5 5870.8 5878.1 5878.5 5886.5 5999.8 21902.5 26609.9 27001.0 27348.9 27610.0 2330.9 2376.9 2428.6 3175.5 3295.3 13542.8 15355.9 15770.4 16723.9 16800.5 1850.6 1912.2 1953.4 1954.1 1955.8 123160.4 126972.4 127451.7 129107.0 129772.1 7945.4 9600.5 10942.0 11050.9 11184.0 13843.0 13848.7 13852.4 13863.2 13868.3 8608.1 8710.5 8769.7 8799.2 8860.5 8821.3 8830.7 8835.7 9019.1 9047.1 6557.8 6646.9 6675.4 6692.1 6828.7 1569.6 1778.6 1813.8 1818.2 1822.4 643.9 807.0 949.5 1025.7 1026.2 21879.0 22260.2 22573.9 22766.3 22766.5 872.0 957.8 969.6 989.3 1025.9 9378.9 11346.7 11441.3 11470.5 11537.0 579632.0 610538.0 613589.0 614815.0 636303.0 71041.3 71194.3 104645.5 116762.0 116778.0 976.3 1036.5 1044.4 1069.9 1070.5 17898.0 18854.4 19003.3 19298.0 19321.3 42163.5 48463.7 54210.2 59545.7 61408.0 80765.2 80797.6 81055.6 81843.6 82033.6 6735.9 7613.8 8068.3 8242.5 8309.8 10632.4 11846.4 12536.2 13160.0 13224.2 9068.8 10912.9 11108.8 11196.6 11257.3 10263.8 11137.2 11402.9 11427.4 11749.0 11284.7 11364.0 11658.6 11799.2 11835.2 8119.1 8179.4 8181.0 8204.8 8274.0 9167.5 11213.4 11406.6 11519.0 11549.0 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5613.88 Median:5132.23 stddev:3104.67 (60.5%) stddev-sans-first:3097.64 21:07:07 INFO - PROCESS | 1644 | Values: 1454.5 1541.9 1723.8 1884.8 1890.9 8335.9 8847.7 8872.5 8873.0 8883.9 6801.4 7835.5 8059.5 8562.9 8704.2 2336.7 2392.5 2394.3 2399.3 2400.6 2361.1 2387.5 2420.5 2421.4 2422.1 2110.7 2195.0 2325.4 2401.0 2402.0 2366.8 2372.4 2397.4 2400.5 2401.8 2379.7 2380.4 2383.8 2387.7 2389.2 8609.0 8655.4 8655.8 8663.5 8674.0 4203.4 5177.8 5347.3 5347.3 5350.2 3460.2 3509.7 3644.3 3653.7 3662.3 1073.8 1114.0 1114.7 1136.1 1143.6 11014.1 11046.9 11155.4 11182.9 11195.4 4524.2 4622.7 4794.6 4797.8 4816.5 9841.2 9845.1 9849.5 9852.1 9859.8 5252.5 5346.6 5350.8 5354.9 5355.9 4711.4 4862.3 4885.6 4886.2 4893.4 3757.3 3792.1 3822.4 3851.6 3876.4 1007.7 1103.6 1186.1 1189.4 1199.6 679.9 749.8 835.4 842.8 930.2 5707.1 5744.7 5898.3 5991.4 6155.7 838.5 914.3 945.5 967.2 968.1 3553.5 3632.6 3878.8 3941.3 4027.9 10340.2 10625.9 10640.1 10644.2 10652.5 10518.6 10600.8 10637.6 10640.9 10648.5 7549.6 7984.2 8083.4 8092.8 8104.4 4818.0 5848.8 6007.4 6047.8 6112.9 8183.9 9365.9 9759.7 9837.4 9976.5 9311.5 9901.9 9954.4 10015.7 10065.9 7488.7 7606.4 7608.0 7609.6 7613.6 7700.1 7934.2 8051.7 8120.7 8191.4 6047.7 6165.9 7365.6 7491.6 7510.2 7177.7 7273.4 7275.6 7329.1 7443.2 6899.3 6913.4 6982.7 6982.9 6995.3 4884.6 4986.2 5025.2 5060.0 5182.7 5023.5 5028.3 5086.6 5242.8 5243.0 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4405.44 Median:4947.25 stddev:1354.05 (27.4%) stddev-sans-first:1322.17 21:07:07 INFO - PROCESS | 1644 | Values: 291.7 302.4 304.0 308.9 310.7 4380.5 4565.5 4789.8 5070.0 5313.7 4371.2 4418.1 4551.0 4936.5 5288.2 3080.5 3845.5 3904.9 4009.9 4031.4 3832.2 4027.9 4028.6 4055.8 4082.2 3454.6 3572.9 3809.9 3996.1 4036.8 3755.5 3781.7 3912.4 3923.2 4057.8 2883.8 2883.8 3224.0 3472.9 3511.3 4874.5 5096.5 5173.5 5193.2 5239.4 4214.6 4440.3 4499.8 4534.5 4568.0 5472.3 5483.4 5490.0 5494.4 5537.6 3852.5 3853.9 3860.7 3874.6 3880.3 4979.6 5129.9 5245.9 5901.2 5930.3 3162.8 3629.6 3760.8 3782.0 3788.5 3827.6 3840.8 3847.9 3851.2 3852.8 4505.3 4505.8 4507.4 4511.5 4638.8 5363.6 5401.5 5453.0 5502.7 5543.2 1097.8 1135.0 1284.6 1303.5 1308.4 761.6 790.0 806.2 815.7 818.7 4377.4 5014.8 5041.6 5045.4 5058.5 5526.6 5716.9 5735.9 5745.6 5752.7 4347.4 4849.6 4953.1 5201.3 5218.2 2949.3 2992.8 2998.6 3006.7 3063.2 4776.3 4974.4 4997.5 5436.5 5957.3 4902.3 5122.2 5375.7 5810.4 5853.8 5925.1 5949.9 5954.9 5956.5 5958.6 4579.3 4943.4 5470.8 5582.5 5682.7 5226.7 5509.5 5591.7 5597.7 5615.0 4763.6 4951.1 5164.5 5780.7 5902.5 3455.4 3710.3 3752.1 3763.6 3781.8 4776.3 5408.3 5580.3 5589.7 5607.0 4868.3 4981.9 5006.4 5404.9 5484.5 5055.6 5062.6 5389.4 5403.4 5417.9 5326.5 5331.5 5332.7 5336.9 5339.7 4750.0 5020.7 5190.9 5337.3 5343.1 4810.3 4931.8 5233.4 5286.3 5402.3 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:25609.20 Median:2510.06 stddev:41717.59 (1662.0%) stddev-sans-first:41792.80 21:07:07 INFO - PROCESS | 1644 | Values: 730.8 767.5 787.3 817.2 850.1 14980.0 15924.9 15925.1 15947.0 15959.6 14370.8 14546.2 14559.0 14567.7 14571.3 660.7 676.8 704.0 836.5 861.6 846.3 864.2 866.7 867.5 867.8 919.1 923.7 924.3 925.1 927.9 715.0 832.8 919.2 921.3 925.1 681.1 681.2 681.7 683.1 684.3 14506.9 14523.6 14543.0 14545.1 14556.6 11403.9 14159.2 14272.0 14302.6 14305.1 12994.7 13055.6 13112.5 13260.8 13326.6 4596.1 4618.8 4709.4 4838.6 4839.9 118863.0 120037.4 124595.4 124731.0 130941.0 11268.4 11367.7 11431.5 11452.2 11601.1 23502.4 23525.0 23543.4 23557.8 23578.1 7679.5 7734.2 8070.4 8220.4 8228.7 4483.7 4486.5 4491.2 4538.5 4571.6 3474.9 3609.6 3629.9 3630.4 3632.4 567.2 572.1 576.5 579.4 585.6 458.0 584.2 625.2 647.0 710.9 8398.3 9726.2 9861.6 9884.2 9919.1 612.4 660.2 672.9 680.6 683.9 4497.3 4562.1 4796.5 4805.9 4838.3 101407.0 142578.3 158782.0 163417.0 164902.0 101507.0 120944.0 136392.0 142184.0 144535.8 117050.0 123826.4 123907.0 124787.0 127338.8 8716.4 8845.3 8884.9 8901.8 9080.6 61809.7 63267.4 63489.7 64423.4 64519.0 93455.0 96778.5 97614.0 98314.0 98995.0 11778.9 12100.8 12156.5 12224.8 12401.0 13408.6 13560.3 13687.5 13807.6 14091.8 12386.8 12549.6 12621.6 12693.0 12704.5 11677.6 11912.0 11989.5 12089.3 12569.2 12065.8 12280.3 12540.3 12620.3 12824.9 8866.4 9147.5 9403.8 9412.9 9540.3 8275.0 9256.4 9637.5 9761.7 9802.6 21:07:07 INFO - PROCESS | 1644 | -------- Summary: end -------- 21:07:07 INFO - PROCESS | 1644 | 21:07:07 INFO - TEST-INFO | 1644: exit 0 21:07:07 INFO - TEST-OK | dromaeo_css | took 1116249ms 21:07:07 INFO - TEST-START | kraken 21:07:07 INFO - operating with platform_type : mac_ 21:07:07 INFO - Initialising browser for kraken test... 21:07:07 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmp_7jm3h/profile http://localhost:49263/getInfo.html) 21:07:10 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 21:07:10 INFO - PROCESS | 1654 | colorDepth:24 21:07:10 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 21:07:10 INFO - PROCESS | 1654 | __metrics 21:07:10 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:07:10 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:07:10 INFO - TEST-INFO | 1654: exit 0 21:07:10 INFO - Browser initialized. 21:07:10 INFO - Running cycle 1/1 for kraken test... 21:07:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmp_7jm3h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:07:22 INFO - PROCESS | 1655 | RSS: Main: 228405248 21:07:22 INFO - PROCESS | 1655 | 21:07:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 21:07:26 INFO - PROCESS | 1655 | RSS: Main: 293748736 21:07:26 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 21:07:29 INFO - PROCESS | 1655 | RSS: Main: 294248448 21:07:29 INFO - PROCESS | 1655 | 21:07:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 21:07:33 INFO - PROCESS | 1655 | RSS: Main: 382144512 21:07:33 INFO - PROCESS | 1655 | 21:07:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 21:07:36 INFO - PROCESS | 1655 | RSS: Main: 281821184 21:07:36 INFO - PROCESS | 1655 | 21:07:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:07:39 INFO - PROCESS | 1655 | RSS: Main: 285597696 21:07:39 INFO - PROCESS | 1655 | 21:07:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 21:07:42 INFO - PROCESS | 1655 | RSS: Main: 323039232 21:07:42 INFO - PROCESS | 1655 | 21:07:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 21:07:46 INFO - PROCESS | 1655 | RSS: Main: 324337664 21:07:46 INFO - PROCESS | 1655 | 21:07:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 21:07:49 INFO - PROCESS | 1655 | RSS: Main: 323371008 21:07:49 INFO - PROCESS | 1655 | 21:07:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:07:52 INFO - PROCESS | 1655 | RSS: Main: 301809664 21:07:52 INFO - PROCESS | 1655 | 21:07:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:07:54 INFO - PROCESS | 1655 | RSS: Main: 302940160 21:07:54 INFO - PROCESS | 1655 | 21:07:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:07:57 INFO - PROCESS | 1655 | RSS: Main: 306040832 21:07:57 INFO - PROCESS | 1655 | 21:08:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:08:01 INFO - PROCESS | 1655 | RSS: Main: 314023936 21:08:01 INFO - PROCESS | 1655 | 21:08:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:08:04 INFO - PROCESS | 1655 | RSS: Main: 316350464 21:08:04 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 21:08:06 INFO - PROCESS | 1655 | RSS: Main: 360062976 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | __start_tp_report 21:08:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 21:08:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 21:08:06 INFO - PROCESS | 1655 | |i|pagename|runs| 21:08:06 INFO - PROCESS | 1655 | |0;ai-astar;95;110;105;111;110;112;114;112;111;111 21:08:06 INFO - PROCESS | 1655 | |1;audio-beat-detection;144;107;128;158;105;128;125;108;193;135 21:08:06 INFO - PROCESS | 1655 | |2;audio-dft;183;187;185;182;183;184;184;179;183;196 21:08:06 INFO - PROCESS | 1655 | |3;audio-fft;80;74;77;152;70;56;58;63;145;57 21:08:06 INFO - PROCESS | 1655 | |4;audio-oscillator;83;82;137;69;72;81;118;94;98;152 21:08:06 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;85;87;85;87;82;90;130;86;84 21:08:06 INFO - PROCESS | 1655 | |6;imaging-darkroom;93;92;93;94;93;92;93;95;106;148 21:08:06 INFO - PROCESS | 1655 | |7;imaging-desaturate;86;86;84;126;138;122;86;81;85;86 21:08:06 INFO - PROCESS | 1655 | |8;json-parse-financial;55;55;57;56;56;56;53;54;57;53 21:08:06 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;65;73;68;80;76;71;69;69;69 21:08:06 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;88;86;87;97;83;85;86;85;83;89 21:08:06 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;129;139;134;140;129;167;158;139;228;129 21:08:06 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;221;168;170;169;163;161;167;162;165;168 21:08:06 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;69;87;87;87;88;70;68;67;65 21:08:06 INFO - PROCESS | 1655 | __end_tp_report 21:08:06 INFO - PROCESS | 1655 | __start_cc_report 21:08:06 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2085 21:08:06 INFO - PROCESS | 1655 | __end_cc_report 21:08:06 INFO - PROCESS | 1655 | __startTimestamp1471838886700__endTimestamp 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | ------- Summary: start ------- 21:08:06 INFO - PROCESS | 1655 | Number of tests: 14 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:109.10 Median:111.50 stddev:5.47 (4.9%) stddev-sans-first:2.45 21:08:06 INFO - PROCESS | 1655 | Values: 95.0 110.0 105.0 111.0 110.0 112.0 114.0 112.0 111.0 111.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:131.50 stddev:26.98 (20.5%) stddev-sans-first:28.32 21:08:06 INFO - PROCESS | 1655 | Values: 144.0 107.0 128.0 158.0 105.0 128.0 125.0 108.0 193.0 135.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.00 stddev:4.50 (2.4%) stddev-sans-first:4.74 21:08:06 INFO - PROCESS | 1655 | Values: 183.0 187.0 185.0 182.0 183.0 184.0 184.0 179.0 183.0 196.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:83.20 Median:66.50 stddev:35.48 (53.4%) stddev-sans-first:37.61 21:08:06 INFO - PROCESS | 1655 | Values: 80.0 74.0 77.0 152.0 70.0 56.0 58.0 63.0 145.0 57.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:81.50 stddev:28.16 (34.6%) stddev-sans-first:29.30 21:08:06 INFO - PROCESS | 1655 | Values: 83.0 82.0 137.0 69.0 72.0 81.0 118.0 94.0 98.0 152.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:86.50 stddev:14.11 (16.3%) stddev-sans-first:14.92 21:08:06 INFO - PROCESS | 1655 | Values: 87.0 85.0 87.0 85.0 87.0 82.0 90.0 130.0 86.0 84.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:93.00 stddev:17.40 (18.7%) stddev-sans-first:18.28 21:08:06 INFO - PROCESS | 1655 | Values: 93.0 92.0 93.0 94.0 93.0 92.0 93.0 95.0 106.0 148.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:85.50 stddev:21.58 (25.2%) stddev-sans-first:22.44 21:08:06 INFO - PROCESS | 1655 | Values: 86.0 86.0 84.0 126.0 138.0 122.0 86.0 81.0 85.0 86.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.48 (2.6%) stddev-sans-first:1.56 21:08:06 INFO - PROCESS | 1655 | Values: 55.0 55.0 57.0 56.0 56.0 56.0 53.0 54.0 57.0 53.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.00 stddev:4.60 (6.6%) stddev-sans-first:4.57 21:08:06 INFO - PROCESS | 1655 | Values: 66.0 65.0 73.0 68.0 80.0 76.0 71.0 69.0 69.0 69.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.50 stddev:4.04 (4.7%) stddev-sans-first:4.27 21:08:06 INFO - PROCESS | 1655 | Values: 88.0 86.0 87.0 97.0 83.0 85.0 86.0 85.0 83.0 89.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:139.50 stddev:30.46 (21.8%) stddev-sans-first:31.42 21:08:06 INFO - PROCESS | 1655 | Values: 129.0 139.0 134.0 140.0 129.0 167.0 158.0 139.0 228.0 129.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:168.00 stddev:17.70 (10.5%) stddev-sans-first:3.26 21:08:06 INFO - PROCESS | 1655 | Values: 221.0 168.0 170.0 169.0 163.0 161.0 167.0 162.0 165.0 168.0 21:08:06 INFO - PROCESS | 1655 | 21:08:06 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:78.50 stddev:10.11 (12.9%) stddev-sans-first:10.35 21:08:06 INFO - PROCESS | 1655 | Values: 68.0 69.0 87.0 87.0 87.0 88.0 70.0 68.0 67.0 65.0 21:08:06 INFO - PROCESS | 1655 | -------- Summary: end -------- 21:08:06 INFO - PROCESS | 1655 | 21:08:07 INFO - TEST-INFO | 1655: exit 0 21:08:07 INFO - TEST-OK | kraken | took 59645ms 21:08:07 INFO - SUITE-END | took 1175s 21:08:07 INFO - Completed test suite (00:19:36) 21:08:07 INFO - javascript benchmark 21:08:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2930.428566429546, 3053.3282677810876, 3092.5670406143145, 3093.7060979206976, 3107.6736942621023, 5483.999999999995, 5606.663600183992, 5672.602917795756, 5694.2597462329895, 5792.884142317156, 5647.632903861257, 5695.544356451484, 5701.6864072476, 5717.857053573663, 5723.084306510959, 5150.639455238135, 5189.273501709764, 5212.400573934008, 5222.981518603855, 5281.339832520935, 5221.2429197766305, 5326.786928522854, 5333.933213357344, 5334.813281535147, 5339.5461385782255, 4163.437935878648, 5123.129068058421, 5355.036093503168, 5366.436524164965, 5416.727069138749, 4339.93671550469, 5114.565261952738, 5263.6578622389425, 5274.235235890794, 5296.126139187029, 5084.288199652052, 5151.639385243035, 5190.429052804188, 5235.502627250406, 5259.184826351921, 5592.328920529562, 5699.430056994309, 5726.685032323262, 5730.36965933747, 5746.109353050284, 5568.9443105569235, 5890.587658863881, 6244.750209991595, 6253.249610046778, 6260.561760676755, 6198.876022479571, 6245.375462453764, 6250.562460627758, 6277.591956522857, 6316.05259211122, 5780.624259423124, 5780.826575202708, 5790.36306006339, 5795.88408231833, 5795.9420405796245, 6244.93836047873, 6312.4634406076, 6312.873742525124, 6317.3366796486625, 6328.303886572473, 5925.970370148121, 6065.575409721366, 6083.939160608337, 6156.8768624627255, 6172.876542469216, 6228.532860035515, 6240.219972503437, 6278.717457714414, 6289.119523266746, 6295.96852015737, 5969.403059693996, 6215.409536094064, 6227.0, 6229.626222426669, 6256.186695729617, 5899.439553242435, 6069.787557435557, 6073.0, 6168.259808822881, 6191.566590338633, 4903.41159060915, 4930.556249937523, 4938.209886418156, 4943.110240156806, 4943.307936888838, 4039.414284928669, 4199.895002624898, 4219.029623186623, 4222.767747773902, 4227.154569086195, 5601.047821870292, 5666.178404131378, 5671.064274394761, 5728.684922329228, 5731.5701322400155, 6317.210348706411, 6321.620702757849, 6334.619922804554, 6337.6197428154455, 6359.8092057238355, 5521.365043020104, 5699.2590963174525, 5703.885922281531, 5720.771169153188, 5721.313442386857, 5359.383670877821, 5412.431694672082, 5420.295361602966, 5479.424660410679, 5479.643823151482, 5518.91721624176, 6257.2804127525005, 6336.461400780985, 6363.745450181941, 6365.586236894587, 6157.983932651242, 6360.522960777867, 6372.203474565679, 6378.489720822416, 6386.201724784402, 6271.059341098871, 6300.338464461349, 6340.175777148941, 6390.0, 6395.1046853441485, 5979.501991335118, 6014.398560144125, 6032.366601506778, 6039.094135879477, 6040.969795150996, 5930.555208359304, 6012.669303188367, 6181.660008699565, 6197.25632924052, 6213.9067913980425, 5786.428721310286, 5788.160716696139, 5838.153467747238, 5926.2295901532525, 5929.584929054925, 5890.293164820249, 5913.379095194942, 5919.23050003514, 5919.526437884872, 5924.644521328734, 5559.305086864142, 5713.228714123707, 5763.596548241583, 5763.596548241583, 5767.192593036894, 5046.8644554974835, 5747.08046712524, 5771.0, 5858.121281807763, 5859.619124756877, 5642.971785141048, 5742.0, 5751.367349591458, 5784.566157538286, 5787.508061814625], "value": 5618.48680389233, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.87150905010174, 508.0753029486334, 512.9025485157822, 514.7220500929499, 558.4865015807168, 29555.0, 38435.23129537408, 39429.40855887157, 39508.814735557906, 40929.79535102328, 36010.27979440416, 41045.38431923516, 41149.58850411495, 41175.58824411755, 41213.17573648525, 9638.084381983717, 9833.213342932548, 9835.852462213037, 9835.95082024589, 9845.507724613777, 8795.956020219923, 9013.504257265848, 9896.455694936776, 11474.737778844363, 12092.395380230997, 10779.5688172473, 12006.459709313101, 12043.397830108503, 12047.759044819099, 12058.69853253667, 11948.283103013804, 12005.339706316148, 12042.0, 12055.517779288817, 12063.974562162226, 11856.644300670994, 11932.926036656656, 11937.761244775143, 11942.343171125585, 11953.760924781544, 39144.02139946495, 39411.21177576432, 39433.60566394328, 39456.01359965995, 39457.80271098655, 3005.7144571265912, 3557.9326202139514, 3701.907452313687, 3702.4446333049987, 4129.463169787939, 30866.84566577153, 30879.536806947915, 30902.0, 30961.0, 31061.378772424425, 3230.2731885325848, 3318.2533929865585, 3342.7660063795543, 3398.3543126805826, 3402.030421329909, 512388.0, 513791.0, 514620.4268978669, 515040.0, 521710.0, 55006.77435643607, 55343.0, 55436.0, 55455.89088218213, 55490.72254638701, 58327.0, 58338.7083064582, 58385.416145838, 58456.830863383344, 58513.70743146257, 16124.435644752377, 16316.184190790413, 16343.019418834909, 16360.918195408947, 16396.508104756875, 11570.0, 11746.29522228669, 12819.038572107129, 12876.935615321863, 12911.902488288413, 14461.78307325391, 14798.408063677332, 14803.0378025428, 15224.923875380553, 15252.618684532754, 5555.916661250085, 6589.406953374123, 6631.535792494576, 6649.268580456146, 6651.700673469705, 917.0096296000304, 924.5793164110314, 950.3205208275978, 960.9621608662627, 1118.669992352258, 28221.15336539907, 29686.55470167949, 29743.85128074389, 29773.851130744206, 30468.84765576158, 946.1720994130136, 1015.7562185075676, 1026.6047571684805, 1095.7917995580815, 1107.3854011023798, 14681.192534410497, 15279.923600381928, 15339.61650958735, 15430.919835611623, 15779.68440631181, 629652.0, 635744.0, 636730.0, 637178.0, 637297.0, 48798.51201488011, 55380.0, 56021.71989140028, 56228.0, 60088.098678519855, 1357.2263809628416, 1369.205860600849, 1405.6766943602825, 1408.4577437686564, 1410.8659677330843, 26079.044981453462, 30056.46676534659, 30897.91857284984, 31059.640880678642, 31378.843105784326, 47996.28005579989, 48549.5145048545, 48568.0, 48591.514084858696, 48671.75664121657, 537541.0, 543389.0, 544208.0, 544923.0, 545593.0, 37800.0, 38342.84971450861, 38500.22999540049, 38595.614043859205, 38603.420948686355, 47087.764561178345, 47535.28697069616, 47755.52244477511, 48070.79823004313, 48177.75911120422, 38539.0, 38646.033849153995, 39005.80497097496, 39924.80037599793, 40261.19477610373, 37655.0, 38465.2306953865, 39990.200195995334, 40001.79999099986, 42306.7884660587, 38544.6145538541, 40824.1835163301, 41473.1705365897, 41827.58172418237, 42392.57607423886, 33897.49153762745, 39862.800685996386, 41216.587834122474, 42860.92847678833, 42909.42177516903, 25740.61389079203, 31405.842970785, 32087.358252835278, 32896.1775955603, 32925.67074329226], "value": 20479.532827584917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.20965850981554, 356.2181012677173, 371.22043708212755, 374.31687170913074, 406.5934065934066, 15872.365105395786, 15884.0, 20894.0, 21005.264815731432, 21038.368848934555, 20693.793062069373, 20713.63019711117, 21174.470638234052, 21211.939403029864, 21263.787362126375, 5931.288245410546, 6049.062395328731, 6055.5761096723245, 6139.846503837395, 6203.2245969253845, 6021.72902219399, 6133.693315334238, 6137.110119032737, 6137.785177518787, 6150.6924653767355, 4231.894202644928, 6364.363563643645, 6769.356911093439, 6829.351211634887, 6914.135733033371, 6555.901661475082, 6739.427148692367, 6814.48891333152, 6845.657717114149, 6874.450043996493, 5870.823875283706, 5878.118282257696, 5878.5297176225795, 5886.470217680387, 5999.76000959961, 21902.45243868916, 26609.866950665124, 27001.0, 27348.906043758227, 27610.0, 2330.860148391102, 2376.881155942196, 2428.550718117154, 3175.523671449278, 3295.340931813623, 13542.796858047039, 15355.923220383938, 15770.448034318744, 16723.91638041802, 16800.495985120466, 1850.5928695686928, 1912.2159914435153, 1953.433504283756, 1954.0718158874563, 1955.7555305586802, 123160.38419807934, 126972.36513817465, 127451.7254827449, 129107.0, 129772.05341919878, 7945.364370850318, 9600.471974041355, 10941.96051375118, 11050.889491105147, 11184.0, 13843.0, 13848.653783655489, 13852.376643051088, 13863.168209907439, 13868.306584670727, 8608.139186081342, 8710.520921349387, 8769.693060742844, 8799.164079412445, 8860.512671803113, 8821.338399620054, 8830.690925817566, 8835.69075082369, 9019.052999435096, 9047.095290470901, 6557.803265902031, 6646.86706265872, 6675.399214070757, 6692.122387373476, 6828.658567071627, 1569.591906104421, 1778.6087060846594, 1813.7823461184742, 1818.2272534173187, 1822.3712819077398, 643.8905386084377, 806.9636866341028, 949.520492151459, 1025.7435641089728, 1026.1585499890018, 21879.0, 22260.22089226869, 22573.88713056457, 22766.31701048971, 22766.544669106526, 872.0, 957.818014577228, 969.5879251318162, 989.2926557511414, 1025.9487025648837, 9378.85931711025, 11346.716332091599, 11441.31352118859, 11470.54117835294, 11537.0, 579632.0, 610538.0, 613589.0, 614815.0, 636303.0, 71041.2895871045, 71194.2880571198, 104645.47677261564, 116762.0, 116778.0, 976.3312131190157, 1036.461040259061, 1044.3785947361307, 1069.9197560183018, 1070.4594179939081, 17898.010446154625, 18854.434366969013, 19003.334883279018, 19298.0, 19321.323753668556, 42163.459135216195, 48463.66899641107, 54210.186847198114, 59545.702271488364, 61408.0, 80765.19234807577, 80797.59601201957, 81055.59472202601, 81843.5907820457, 82033.58983205046, 6735.932640673726, 7613.771586852404, 8068.273855353046, 8242.546659933761, 8309.792255193668, 10632.41521716313, 11846.407679615812, 12536.247825130522, 13160.0, 13224.206547607175, 9068.773280667772, 10912.890871091187, 11108.778034416046, 11196.552137914392, 11257.324560526395, 10263.846042309222, 11137.220394572301, 11402.94298528502, 11427.371494567878, 11749.0, 11284.661460156209, 11364.0, 11658.551704142224, 11799.233049851731, 11835.230710003822, 8119.106898241307, 8179.386546009192, 8181.036551227781, 8204.810302506223, 8274.0, 9167.49578773174, 11213.439328033402, 11406.600768973047, 11519.020883224684, 11549.018333441416], "value": 9863.963796419854, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.461849115827, 1541.9074855508675, 1723.8189990051044, 1884.7738271407434, 1890.9338173163942, 8335.874961875577, 8847.73456796297, 8872.512011839355, 8872.955635221831, 8883.866741998876, 6801.387875091241, 7835.451518393715, 8059.476134051282, 8562.871556926651, 8704.216620504154, 2336.696229490168, 2392.461696118377, 2394.3295877154424, 2399.3042017814814, 2400.6159014557675, 2361.126383238204, 2387.4747555537756, 2420.491696743686, 2421.406755344943, 2422.0917156066475, 2110.6517424624917, 2195.0, 2325.4186453386656, 2400.9519809603885, 2402.039184326266, 2366.8461549999197, 2372.3950392649936, 2397.352714767012, 2400.543896659629, 2401.8438801477846, 2379.6906402167606, 2380.357303528055, 2383.833131680783, 2387.7015373078366, 2389.187676190086, 8609.009963854174, 8655.35084868631, 8655.783605409852, 8663.48019118855, 8674.0, 4203.369494575808, 5177.818776342848, 5347.25138480613, 5347.304850369427, 5350.170723537858, 3460.1522626956557, 3509.701675357572, 3644.2893635741043, 3653.707703383723, 3662.285854258421, 1073.8335557988523, 1114.0, 1114.7380365614074, 1136.0911270983263, 1143.6283207957447, 11014.118870490342, 11046.944765276203, 11155.38645374504, 11182.944085279521, 11195.440227988649, 4524.2082635538645, 4622.722636641813, 4794.5924596409695, 4797.784099715521, 4816.518348165156, 9841.163501102343, 9845.064718851698, 9849.50752462374, 9852.113309802153, 9859.802803943881, 5252.527272545489, 5346.625736198506, 5350.839474815762, 5354.946450535523, 5355.919661205085, 4711.387519622427, 4862.343583616237, 4885.5847252983895, 4886.218205087169, 4893.437254715754, 3757.3236817373136, 3792.146766977408, 3822.369309064029, 3851.6340947609933, 3876.441548577925, 1007.6573964851985, 1103.6358001859367, 1186.0511590727383, 1189.4350183662864, 1199.5904811846046, 679.8572299816994, 749.8425330680509, 835.4068611285934, 842.8103676672822, 930.2232635749225, 5707.1153971134545, 5744.712764361765, 5898.292204935436, 5991.430814072656, 6155.72299246535, 838.5262326785347, 914.3325372478129, 945.4610871803006, 967.2455484721928, 968.0754879090451, 3553.4847447120023, 3632.582253040934, 3878.825452854628, 3941.3102707026155, 4027.9194416111513, 10340.224483163793, 10625.937406259312, 10640.148788096934, 10644.201684873666, 10652.520636571373, 10518.579256829642, 10600.787984240273, 10637.574497020032, 10640.946795265974, 10648.520816563274, 7549.622518874145, 7984.241497057887, 8083.434159608771, 8092.797680057928, 8104.432689711663, 4817.975910120427, 5848.766049357978, 6007.3992600739575, 6047.758089676364, 6112.877742445037, 8183.8772418414965, 9365.85951210746, 9759.658411955548, 9837.360571562825, 9976.451295178835, 9311.534423278945, 9901.900980990386, 9954.402735835873, 10015.69952901414, 10065.899341006496, 7488.737894173678, 7606.353459955966, 7608.0, 7609.581473019037, 7613.619319034137, 7700.075990881131, 7934.246246606564, 8051.718189863327, 8120.67517299325, 8191.385646076687, 6047.697615119315, 6165.907511387422, 7365.631718414165, 7491.550506969599, 7510.173880873209, 7177.712891484282, 7273.418126549757, 7275.599842008741, 7329.083864516935, 7443.218462061622, 6899.275576064641, 6913.3777959983845, 6982.650867456506, 6982.860342793014, 6995.265497122728, 4884.584810291023, 4986.202207646759, 5025.195968644999, 5060.0, 5182.714950677749, 5023.547880690755, 5028.296038554532, 5086.64393492452, 5242.76407561653, 5242.97378513105], "value": 4525.3076097137655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.728692316146, 302.4314289136423, 304.03317450507393, 308.877993192689, 310.706382468567, 4380.540043295456, 4565.5206203348625, 4789.778606455353, 5069.974650126745, 5313.734313284335, 4371.2350338690685, 4418.094290670413, 4550.954490455095, 4936.469694394731, 5288.206768984644, 3080.522519009557, 3845.538535375759, 3904.941425878614, 4009.87723437438, 4031.3549832026883, 3832.2335532893535, 4027.8993025174314, 4028.5769994150633, 4055.817488213023, 4082.163156552914, 3454.5509083819193, 3572.92854142917, 3809.942850857239, 3996.081880697267, 4036.757794532323, 3755.455458958436, 3781.6953151162943, 3912.3544615138467, 3923.195744872294, 4057.797110144496, 2883.7692984561186, 2883.8320480205552, 3224.0172294039, 3472.8641885240395, 3511.3328467591077, 4874.53691899269, 5096.490350964911, 5173.456787037345, 5193.221016847466, 5239.397469291004, 4214.64175545076, 4440.289553671397, 4499.842505512292, 4534.546545345439, 4568.0407114506, 5472.34331880173, 5483.424240454775, 5489.972550137264, 5494.36814766303, 5537.612367134302, 3852.537695476533, 3853.863110382444, 3860.6718428933295, 3874.593167717377, 3880.2627500775043, 4979.601631869442, 5129.897402051975, 5245.901639344293, 5901.196931457566, 5930.258717660293, 3162.826044567525, 3629.637036296402, 3760.811959402019, 3781.9810900945317, 3788.5264341957254, 3827.598102199229, 3840.7695538267794, 3847.942280865789, 3851.2105018471148, 3852.768833869921, 4505.256632655641, 4505.797239124247, 4507.414036175342, 4511.548845115528, 4638.768061596907, 5363.594117735266, 5401.459854014645, 5453.0, 5502.669839809624, 5543.196236545678, 1097.8023955687893, 1134.9728495711395, 1284.6081945006677, 1303.5372442782675, 1308.358904136985, 761.6458346868621, 789.9921000790033, 806.2260230178977, 815.7267315449272, 818.6970820796344, 4377.409049778239, 5014.774335154927, 5041.6470847040355, 5045.360257916192, 5058.519440653132, 5526.640768350045, 5716.942830571724, 5735.913961290584, 5745.626534275342, 5752.6836024019085, 4347.391365208873, 4849.612031037579, 4953.083679519301, 5201.269333599815, 5218.243354713545, 2949.336399310181, 2992.835394053348, 2998.625171853518, 3006.6920889413177, 3063.249503871521, 4776.280539005955, 4974.427940786856, 4997.5002499749735, 5436.483534064337, 5957.314908785545, 4902.313676085351, 5122.208396423884, 5375.6774593524515, 5810.360860305363, 5853.795117170878, 5925.05199168131, 5949.940500595025, 5954.851128721816, 5956.52347812174, 5958.582899197015, 4579.313103034571, 4943.382077240345, 5470.839018587715, 5582.525485333792, 5682.659040457585, 5226.712530810842, 5509.5041446270025, 5591.664500130005, 5597.748101335531, 5614.989301925692, 4763.642726795435, 4951.10880041596, 5164.535191832603, 5780.682062486603, 5902.455109565696, 3455.3911796594416, 3710.2579484103826, 3752.080740218664, 3763.6048214938132, 3781.77092444951, 4776.283557466408, 5408.323959505062, 5580.330360356783, 5589.692566908859, 5606.990741666539, 4868.318435419115, 4981.900361992813, 5006.424261210008, 5404.891902161856, 5484.533814625642, 5055.595552355876, 5062.594992400673, 5389.434109418455, 5403.352357344951, 5417.945820541901, 5326.493983071602, 5331.493508116722, 5332.73336333174, 5336.9466305338, 5339.650553756334, 4749.976250118865, 5020.698758074527, 5190.870228244324, 5337.279467271868, 5343.145096784496, 4810.302506136661, 4931.753412329441, 5233.371995360429, 5286.286351342674, 5402.297701298806], "value": 3967.4004850962187, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.7551970090019, 767.4972892755244, 787.3425689549226, 817.1929422433936, 850.1201256699316, 14980.026298290604, 15924.920375398136, 15925.124118173495, 15947.0, 15959.601009974756, 14370.784438233408, 14546.199959002251, 14559.0, 14567.708645827104, 14571.27143642819, 660.696079803291, 676.780046484892, 703.989440158398, 836.5357226739172, 861.6424183963647, 846.3440833354139, 864.2006144316482, 866.6879923227633, 867.5185272173941, 867.8047439326165, 919.1176470588243, 923.6674797073048, 924.3160061554469, 925.1211349218236, 927.8541001862694, 715.0, 832.8334333133398, 919.1819280840058, 921.2537844346068, 925.0841666749869, 681.1247546902207, 681.2029924987776, 681.7204945972128, 683.1051322767191, 684.2815044203567, 14506.927465362605, 14523.564293071226, 14542.981991260616, 14545.127292362386, 14556.636084097876, 11403.885961140366, 14159.22124283164, 14272.0, 14302.57092287233, 14305.14169149844, 12994.740105197938, 13055.608331750063, 13112.541061062913, 13260.801087983687, 13326.600201993955, 4596.080783843245, 4618.8152473901, 4709.387779588632, 4838.588719958808, 4839.879003024917, 118863.0, 120037.38953093148, 124595.3770231143, 124731.02151827831, 130941.0, 11268.436578171058, 11367.715807104889, 11431.485583148778, 11452.198346115692, 11601.12991525622, 23502.412439688804, 23525.0, 23543.41141471443, 23557.764422355558, 23578.05687772504, 7679.500832445873, 7734.187910269453, 8070.394720395994, 8220.424570280024, 8228.670853165926, 4483.686141970031, 4486.461624605068, 4491.214037543482, 4538.546145385501, 4571.634269258451, 3474.9305013900084, 3609.5668519777796, 3629.94555081674, 3630.43728222126, 3632.400653892078, 567.1945836911596, 572.1189368372743, 576.4984463516683, 579.3800633322389, 585.648610833505, 457.9816807327736, 584.1588113117098, 625.2434554189482, 646.9902951455732, 710.9360157585842, 8398.286145677686, 9726.221902247804, 9861.605535778488, 9884.209263258923, 9919.057689519484, 612.3845535237058, 660.2440205964144, 672.8586996730644, 680.6290571638469, 683.9384455398938, 4497.280435130363, 4562.130646888491, 4796.5203479651755, 4805.903881922342, 4838.250071238964, 101407.0, 142578.28710856586, 158782.0, 163417.0, 164902.0, 101507.0, 120944.0, 136392.0, 142184.0, 144535.83196252063, 117050.0, 123826.38086809509, 123907.0, 124787.02555693375, 127338.81652958565, 8716.38985271025, 8845.292376609726, 8884.911150888667, 8901.821963560822, 9080.636774528944, 61809.69095154495, 63267.367326326144, 63489.68255158695, 64423.35576644361, 64519.0, 93455.0, 96778.54832177665, 97614.02385976049, 98314.0, 98995.0, 11778.882211178121, 12100.757984840078, 12156.513739450324, 12224.816627750768, 12401.0, 13408.597742067754, 13560.321983900569, 13687.520936767563, 13807.585772426843, 14091.807250449501, 12386.75226495483, 12549.560765373168, 12621.558245461365, 12692.984561235264, 12704.480575896736, 11677.591284305008, 11911.987481064207, 11989.460474278503, 12089.335086570323, 12569.183003104768, 12065.758684826429, 12280.263184208976, 12540.31028293416, 12620.305883176514, 12824.935875320563, 8866.42368246062, 9147.496887671243, 9403.764905877408, 9412.905870941204, 9540.284478664267, 8275.0, 9256.444613323221, 9637.469939153414, 9761.658341957997, 9802.558884850056], "value": 7787.620306958739, "unit": "score"}], "name": "dromaeo_css", "value": 7358.114878293488, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 110.0, 105.0, 111.0, 110.0, 112.0, 114.0, 112.0, 111.0, 111.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 128.0, 158.0, 105.0, 128.0, 125.0, 108.0, 193.0, 135.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 185.0, 182.0, 183.0, 184.0, 184.0, 179.0, 183.0, 196.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 77.0, 152.0, 70.0, 56.0, 58.0, 63.0, 145.0, 57.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 137.0, 69.0, 72.0, 81.0, 118.0, 94.0, 98.0, 152.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 82.0, 90.0, 130.0, 86.0, 84.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 94.0, 93.0, 92.0, 93.0, 95.0, 106.0, 148.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 126.0, 138.0, 122.0, 86.0, 81.0, 85.0, 86.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 56.0, 56.0, 56.0, 53.0, 54.0, 57.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 68.0, 80.0, 76.0, 71.0, 69.0, 69.0, 69.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 97.0, 83.0, 85.0, 86.0, 85.0, 83.0, 89.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 139.0, 134.0, 140.0, 129.0, 167.0, 158.0, 139.0, 228.0, 129.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 168.0, 170.0, 169.0, 163.0, 161.0, 167.0, 162.0, 165.0, 168.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 87.0, 87.0, 87.0, 88.0, 70.0, 68.0, 67.0, 65.0], "value": 75.6, "unit": "score"}], "name": "kraken", "value": 1505.7, "alertThreshold": 2.0}]} 21:08:07 INFO - Return code: 0 21:08:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:08:08 INFO - # TBPL SUCCESS # 21:08:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:08:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:08:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:08:08 INFO - 2016-08-21 21:08:08.053 screenresolution[1659:10059] starting screenresolution argv=screenresolution get 21:08:08 INFO - 2016-08-21 21:08:08.055 screenresolution[1659:10059] Display 0: 1600x1200x32@60 21:08:08 INFO - 2016-08-21 21:08:08.061 screenresolution[1660:10062] starting screenresolution argv=screenresolution list 21:08:08 INFO - Available Modes on Display 0 21:08:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:08:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:08:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:08:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:08:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:08:08 INFO - Intel Iris: 21:08:08 INFO - Chipset Model: Intel Iris 21:08:08 INFO - Type: GPU 21:08:08 INFO - Bus: Built-In 21:08:08 INFO - VRAM (Dynamic, Max): 1536 MB 21:08:08 INFO - Vendor: Intel (0x8086) 21:08:08 INFO - Device ID: 0x0a2e 21:08:08 INFO - Revision ID: 0x0009 21:08:08 INFO - Displays: 21:08:08 INFO - Display: 21:08:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:08:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:08:08 INFO - Display Serial Number: 1600x1200 60 21:08:08 INFO - Main Display: Yes 21:08:08 INFO - Mirror: Off 21:08:08 INFO - Online: Yes 21:08:08 INFO - Rotation: Supported 21:08:08 INFO - Return code: 0 21:08:08 INFO - Running post-action listener: _resource_record_post_action 21:08:08 INFO - [mozharness: 2016-08-22 04:08:08.296420Z] Finished run-tests step (success) 21:08:08 INFO - Running post-run listener: _resource_record_post_run 21:08:08 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38163456; Write bytes: 704070656; Read time: 381; Write time: 2141 21:08:08 INFO - TinderboxPrint: CPU usage
53.5% 21:08:08 INFO - TinderboxPrint: I/O read bytes / time
38,163,456 / 381 21:08:08 INFO - TinderboxPrint: I/O write bytes / time
704,070,656 / 2,141 21:08:08 INFO - TinderboxPrint: CPU idle
2,252.1 (46.5%) 21:08:08 INFO - TinderboxPrint: CPU system
971.0 (20.1%) 21:08:08 INFO - TinderboxPrint: CPU user
1,618.6 (33.4%) 21:08:08 INFO - TinderboxPrint: Swap in / out
444,399,616 / 0 21:08:08 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 407081472; Write bytes: 408995328; Read time: 29943; Write time: 724 21:08:08 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 34477056; Write bytes: 293572096; Read time: 350; Write time: 1409 21:08:08 INFO - Running post-run listener: _upload_blobber_files 21:08:08 INFO - Blob upload gear active. 21:08:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:08:08 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 21:08:08 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:08:08 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:08:09 INFO - (blobuploader) - INFO - Open directory for files ... 21:08:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:08:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:08:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:08:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:08:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:08:10 INFO - (blobuploader) - INFO - Done attempting. 21:08:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:08:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:08:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:08:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:08:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:08:11 INFO - (blobuploader) - INFO - Done attempting. 21:08:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:08:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:08:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:08:16 INFO - (blobuploader) - INFO - Done attempting. 21:08:16 INFO - (blobuploader) - INFO - Iteration through files over. 21:08:16 INFO - Return code: 0 21:08:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:08:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:08:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5450a8ce67a95299437bbe869462b6d02d3efe92cc44bf05138ccd911214becbe0244a6452443571c547a17885a899378a9fa2a9f6e23ce2bb2a865c2b3273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89fd1dac9d70abcb3e5f215e05ef1d9ed4993b5e3bb08f092e3e43bd19d025839e329f4705980eda7fbda43de7ed5671804c00a1ea567d89b09480141f5086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18d5b1cfd8e374ce9f62d29b9e3b55963d3d7e5ffcfa7afc1ad2316ffb95074f1b0ad29201fe6940004e53250638bbaeda05f10da8b61b9272815895f9f56d28"} 21:08:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:08:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:08:16 INFO - Contents: 21:08:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5450a8ce67a95299437bbe869462b6d02d3efe92cc44bf05138ccd911214becbe0244a6452443571c547a17885a899378a9fa2a9f6e23ce2bb2a865c2b3273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89fd1dac9d70abcb3e5f215e05ef1d9ed4993b5e3bb08f092e3e43bd19d025839e329f4705980eda7fbda43de7ed5671804c00a1ea567d89b09480141f5086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18d5b1cfd8e374ce9f62d29b9e3b55963d3d7e5ffcfa7afc1ad2316ffb95074f1b0ad29201fe6940004e53250638bbaeda05f10da8b61b9272815895f9f56d28"} 21:08:16 INFO - Running post-run listener: copy_logs_to_upload_dir 21:08:16 INFO - Copying logs to upload dir... 21:08:16 INFO - mkdir: /builds/slave/test/build/upload/logs 21:08:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.624141 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-21 21:08:16.357683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 21:08:16.362960) ========= 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.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5450a8ce67a95299437bbe869462b6d02d3efe92cc44bf05138ccd911214becbe0244a6452443571c547a17885a899378a9fa2a9f6e23ce2bb2a865c2b3273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89fd1dac9d70abcb3e5f215e05ef1d9ed4993b5e3bb08f092e3e43bd19d025839e329f4705980eda7fbda43de7ed5671804c00a1ea567d89b09480141f5086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18d5b1cfd8e374ce9f62d29b9e3b55963d3d7e5ffcfa7afc1ad2316ffb95074f1b0ad29201fe6940004e53250638bbaeda05f10da8b61b9272815895f9f56d28"} build_url:https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/G-vjD7hhRR-lLq77niIAsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5450a8ce67a95299437bbe869462b6d02d3efe92cc44bf05138ccd911214becbe0244a6452443571c547a17885a899378a9fa2a9f6e23ce2bb2a865c2b3273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89fd1dac9d70abcb3e5f215e05ef1d9ed4993b5e3bb08f092e3e43bd19d025839e329f4705980eda7fbda43de7ed5671804c00a1ea567d89b09480141f5086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18d5b1cfd8e374ce9f62d29b9e3b55963d3d7e5ffcfa7afc1ad2316ffb95074f1b0ad29201fe6940004e53250638bbaeda05f10da8b61b9272815895f9f56d28"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 21:08:16.395731) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 21:08:16.396034) ========= 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.VyW1HpRpE2/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.wUq5uyX11X/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.005304 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 21:08:16.444309) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 21:08:16.444662) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 21:08:16.445003) ========= ========= Total master_lag: 0.17 =========