builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0221 starttime: 1490891451.08 results: success (0) buildid: 20170330080007 builduid: 0a6504e97a594bd5a9c1a0920dc5cb87 revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.081472) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.081964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.082237) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.108768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.109146) ========= 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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 09:30:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-03-30 09:30:51 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.279310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.279636) ========= 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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.041830 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:51.342869) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 09:30:51.343215) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 09:30:51,372 truncating revision to first 12 chars 2017-03-30 09:30:51,372 Setting DEBUG logging. 2017-03-30 09:30:51,372 attempt 1/10 2017-03-30 09:30:51,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3364cc17988c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 09:30:52,875 unpacking tar archive at: mozilla-central-3364cc17988c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.773897 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 09:30:53.244125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:53.244464) ========= script_repo_revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:53.244902) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:53.245167) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:53.266074) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 09:30:53.266493) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:30:53 INFO - MultiFileLogger online at 20170330 09:30:53 in /builds/slave/test 09:30:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:30:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:53 INFO - {'append_to_log': False, 09:30:53 INFO - 'base_work_dir': '/builds/slave/test', 09:30:53 INFO - 'blob_upload_branch': 'Firefox', 09:30:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:53 INFO - 'branch': 'Firefox', 09:30:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:53 INFO - 'config_files': ('talos/mac_config.py',), 09:30:53 INFO - 'default_actions': ('clobber', 09:30:53 INFO - 'read-buildbot-config', 09:30:53 INFO - 'download-and-extract', 09:30:53 INFO - 'populate-webroot', 09:30:53 INFO - 'create-virtualenv', 09:30:53 INFO - 'install', 09:30:53 INFO - 'run-tests'), 09:30:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:53 INFO - 'download_minidump_stackwalk': True, 09:30:53 INFO - 'download_symbols': 'ondemand', 09:30:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:53 INFO - 'gecko_profile': False, 09:30:53 INFO - 'gecko_profile_interval': 0, 09:30:53 INFO - 'installer_path': 'installer.exe', 09:30:53 INFO - 'log_level': 'info', 09:30:53 INFO - 'log_name': 'talos', 09:30:53 INFO - 'log_to_console': True, 09:30:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:53 INFO - 'opt_config_files': (), 09:30:53 INFO - 'pip_index': False, 09:30:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:53 INFO - 'cmd': ('bash', 09:30:53 INFO - '-c', 09:30:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:53 INFO - 'enabled': True, 09:30:53 INFO - 'halt_on_failure': False, 09:30:53 INFO - 'name': 'check_screen_resolution'},), 09:30:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:53 INFO - 'cmd': ('bash', 09:30:53 INFO - '-c', 09:30:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:53 INFO - 'enabled': True, 09:30:53 INFO - 'halt_on_failure': False, 09:30:53 INFO - 'name': 'check_screen_resolution'},), 09:30:53 INFO - 'run_cmd_checks_enabled': True, 09:30:53 INFO - 'suite': 'dromaeojs', 09:30:53 INFO - 'system_bits': '32', 09:30:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:53 INFO - 'title': 't-yosemite-r7-0221', 09:30:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:53 INFO - 'use_talos_json': True, 09:30:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:53 INFO - 'work_dir': 'build'} 09:30:53 INFO - [mozharness: 2017-03-30 16:30:53.405231Z] Running clobber step. 09:30:53 INFO - Running pre-action listener: _resource_record_pre_action 09:30:53 INFO - Running main action method: clobber 09:30:53 INFO - rmtree: /builds/slave/test/build 09:30:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:54 INFO - Running post-action listener: _resource_record_post_action 09:30:54 INFO - [mozharness: 2017-03-30 16:30:54.808454Z] Finished clobber step (success) 09:30:54 INFO - [mozharness: 2017-03-30 16:30:54.808589Z] Running read-buildbot-config step. 09:30:54 INFO - Running pre-action listener: _resource_record_pre_action 09:30:54 INFO - Running main action method: read_buildbot_config 09:30:54 INFO - Using buildbot properties: 09:30:54 INFO - { 09:30:54 INFO - "project": "", 09:30:54 INFO - "product": "firefox", 09:30:54 INFO - "script_repo_revision": "production", 09:30:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:30:54 INFO - "repository": "", 09:30:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:30:54 INFO - "buildid": "20170330080007", 09:30:54 INFO - "pgo_build": "False", 09:30:54 INFO - "basedir": "/builds/slave/test", 09:30:54 INFO - "buildnumber": 13, 09:30:54 INFO - "slavename": "t-yosemite-r7-0221", 09:30:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:30:54 INFO - "platform": "yosemite_r7", 09:30:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:30:54 INFO - "branch": "mozilla-central", 09:30:54 INFO - "repo_path": "mozilla-central", 09:30:54 INFO - "revision": "3364cc17988c013c36f2a8123315db2855393011", 09:30:54 INFO - "stage_platform": "macosx64", 09:30:54 INFO - "builduid": "0a6504e97a594bd5a9c1a0920dc5cb87", 09:30:54 INFO - "slavebuilddir": "test" 09:30:54 INFO - } 09:30:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:30:54 INFO - Running post-action listener: _resource_record_post_action 09:30:54 INFO - [mozharness: 2017-03-30 16:30:54.811944Z] Finished read-buildbot-config step (success) 09:30:54 INFO - [mozharness: 2017-03-30 16:30:54.812056Z] Running download-and-extract step. 09:30:54 INFO - Running pre-action listener: _resource_record_pre_action 09:30:54 INFO - Running main action method: download_and_extract 09:30:54 INFO - mkdir: /builds/slave/test/build/tests 09:30:54 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')]} 09:30:54 INFO - trying https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:54 INFO - Downloading https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:30:56 INFO - Downloaded 1325 bytes. 09:30:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:56 INFO - Using the following test package requirements: 09:30:56 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:30:56 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:30:56 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:30:56 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:30:56 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'jsshell-mac.zip'], 09:30:56 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:30:56 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:30:56 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:30:56 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:30:56 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:30:56 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:56 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:30:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:30:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:30:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:56 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:30:57 INFO - Expected file size: 52511534 09:30:57 INFO - Obtained file size: 52511534 09:31:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:31:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:31:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:13 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:31:15 INFO - Expected file size: 52511534 09:31:15 INFO - Obtained file size: 52511534 09:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:31:32 INFO - Expected file size: 14404152 09:31:32 INFO - Obtained file size: 14404152 09:31:32 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')]} 09:31:32 INFO - trying https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:32 INFO - Downloading https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:31:34 INFO - Downloaded 75533800 bytes. 09:31:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:34 INFO - mkdir: /builds/slave/test/properties 09:31:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:34 INFO - Contents: 09:31:34 INFO - build_url:https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:35 INFO - Running post-action listener: _resource_record_post_action 09:31:35 INFO - Running post-action listener: set_extra_try_arguments 09:31:35 INFO - [mozharness: 2017-03-30 16:31:35.454408Z] Finished download-and-extract step (success) 09:31:35 INFO - [mozharness: 2017-03-30 16:31:35.454545Z] Running populate-webroot step. 09:31:35 INFO - Running pre-action listener: _resource_record_pre_action 09:31:35 INFO - Running main action method: populate_webroot 09:31:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:35 INFO - '--tppagecycles', 09:31:35 INFO - '7'], 09:31:35 INFO - 'tests': ['tsvgm']}}, 09:31:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:31:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['damp', 'tps']}, 09:31:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'tests': ['damp', 'tps']}, 09:31:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['dromaeo_dom']}, 09:31:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['basic_compositor_video']}, 09:31:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:31:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tsvgx', 09:31:35 INFO - 'tsvgr_opacity', 09:31:35 INFO - 'tart', 09:31:35 INFO - 'tscrollx', 09:31:35 INFO - 'cart', 09:31:35 INFO - 'tsvg_static']}, 09:31:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:35 INFO - 'tsvgr_opacity', 09:31:35 INFO - 'tart', 09:31:35 INFO - 'tscrollx', 09:31:35 INFO - 'cart', 09:31:35 INFO - 'tsvg_static']}, 09:31:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tp5o']}, 09:31:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:31:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s', 09:31:35 INFO - '--xperf_path', 09:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:35 INFO - 'tests': ['tp5n']}, 09:31:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:31:35 INFO - 'talos_options': ['--xperf_path', 09:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:35 INFO - 'tests': ['tp5n']}}, 09:31:35 INFO - 'talos.zip': {'path': '', 09:31:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:35 INFO - Running post-action listener: _resource_record_post_action 09:31:35 INFO - [mozharness: 2017-03-30 16:31:35.473064Z] Finished populate-webroot step (success) 09:31:35 INFO - [mozharness: 2017-03-30 16:31:35.473190Z] Running create-virtualenv step. 09:31:35 INFO - Running pre-action listener: _resource_record_pre_action 09:31:35 INFO - Running main action method: create_virtualenv 09:31:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:35 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 09:31:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:35 INFO - Using real prefix '/tools/python27' 09:31:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:39 INFO - Installing pip.................done. 09:31:39 INFO - Return code: 0 09:31:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:31:39 INFO - Reading from file tmpfile_stdout 09:31:39 INFO - Output received: 09:31:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:31:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:39 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')]} 09:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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')]} 09:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:39 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 09:31:39 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 09:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:39 INFO - 'HOME': '/Users/cltbld', 09:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:39 INFO - 'LOGNAME': 'cltbld', 09:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:39 INFO - 'MOZ_NO_REMOTE': '1', 09:31:39 INFO - 'NO_EM_RESTART': '1', 09:31:39 INFO - 'PAGER': '/bin/cat', 09:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:39 INFO - 'PWD': '/builds/slave/test', 09:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:39 INFO - 'SHELL': '/bin/bash', 09:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:39 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:39 INFO - 'USER': 'cltbld', 09:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:39 INFO - 'XPC_FLAGS': '0x0', 09:31:39 INFO - 'XPC_SERVICE_NAME': '0', 09:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:39 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 09:31:39 INFO - Cleaning up... 09:31:39 INFO - Return code: 0 09:31:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:39 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')]} 09:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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')]} 09:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:39 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 09:31:39 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 09:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:39 INFO - 'HOME': '/Users/cltbld', 09:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:39 INFO - 'LOGNAME': 'cltbld', 09:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:39 INFO - 'MOZ_NO_REMOTE': '1', 09:31:39 INFO - 'NO_EM_RESTART': '1', 09:31:39 INFO - 'PAGER': '/bin/cat', 09:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:39 INFO - 'PWD': '/builds/slave/test', 09:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:39 INFO - 'SHELL': '/bin/bash', 09:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:39 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:39 INFO - 'USER': 'cltbld', 09:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:39 INFO - 'XPC_FLAGS': '0x0', 09:31:39 INFO - 'XPC_SERVICE_NAME': '0', 09:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:39 INFO - Downloading/unpacking psutil>=3.1.1 09:31:39 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 09:31:39 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 09:31:39 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 09:31:39 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 09:31:39 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 09:31:39 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 09:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:43 INFO - Installing collected packages: psutil 09:31:43 INFO - Running setup.py install for psutil 09:31:43 INFO - building 'psutil._psutil_osx' extension 09:31:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:31:44 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 09:31:44 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 09:31:44 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 09:31:44 INFO - building 'psutil._psutil_posix' extension 09:31:44 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 09:31:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:44 INFO - ^ 09:31:44 INFO - 1 warning generated. 09:31:44 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 09:31:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:44 INFO - Successfully installed psutil 09:31:44 INFO - Cleaning up... 09:31:44 INFO - Return code: 0 09:31:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:44 INFO - 'HOME': '/Users/cltbld', 09:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:44 INFO - 'LOGNAME': 'cltbld', 09:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:44 INFO - 'MOZ_NO_REMOTE': '1', 09:31:44 INFO - 'NO_EM_RESTART': '1', 09:31:44 INFO - 'PAGER': '/bin/cat', 09:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:44 INFO - 'PWD': '/builds/slave/test', 09:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:44 INFO - 'SHELL': '/bin/bash', 09:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:44 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:44 INFO - 'USER': 'cltbld', 09:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:44 INFO - 'XPC_FLAGS': '0x0', 09:31:44 INFO - 'XPC_SERVICE_NAME': '0', 09:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:47 INFO - Installing collected packages: mozsystemmonitor 09:31:47 INFO - Running setup.py install for mozsystemmonitor 09:31:47 INFO - Successfully installed mozsystemmonitor 09:31:47 INFO - Cleaning up... 09:31:47 INFO - Return code: 0 09:31:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31:47 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')]} 09:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:48 INFO - 'HOME': '/Users/cltbld', 09:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:48 INFO - 'LOGNAME': 'cltbld', 09:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:48 INFO - 'MOZ_NO_REMOTE': '1', 09:31:48 INFO - 'NO_EM_RESTART': '1', 09:31:48 INFO - 'PAGER': '/bin/cat', 09:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:48 INFO - 'PWD': '/builds/slave/test', 09:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:48 INFO - 'SHELL': '/bin/bash', 09:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:48 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:48 INFO - 'USER': 'cltbld', 09:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:48 INFO - 'XPC_FLAGS': '0x0', 09:31:48 INFO - 'XPC_SERVICE_NAME': '0', 09:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:48 INFO - Downloading/unpacking jsonschema==2.5.1 09:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:52 INFO - warning: no files found matching '*.txt' 09:31:52 INFO - no previously-included directories found matching 'build' 09:31:52 INFO - no previously-included directories found matching 'dist' 09:31:52 INFO - no previously-included directories found matching '.git*' 09:31:52 INFO - Installing collected packages: jsonschema, functools32 09:31:52 INFO - Running setup.py install for functools32 09:31:52 INFO - warning: no files found matching '*.txt' 09:31:52 INFO - no previously-included directories found matching 'build' 09:31:52 INFO - no previously-included directories found matching 'dist' 09:31:52 INFO - no previously-included directories found matching '.git*' 09:31:52 INFO - Successfully installed jsonschema functools32 09:31:52 INFO - Cleaning up... 09:31:52 INFO - Return code: 0 09:31:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:52 INFO - 'HOME': '/Users/cltbld', 09:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:52 INFO - 'LOGNAME': 'cltbld', 09:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:52 INFO - 'MOZ_NO_REMOTE': '1', 09:31:52 INFO - 'NO_EM_RESTART': '1', 09:31:52 INFO - 'PAGER': '/bin/cat', 09:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:52 INFO - 'PWD': '/builds/slave/test', 09:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:52 INFO - 'SHELL': '/bin/bash', 09:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:52 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:52 INFO - 'USER': 'cltbld', 09:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:52 INFO - 'XPC_FLAGS': '0x0', 09:31:52 INFO - 'XPC_SERVICE_NAME': '0', 09:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:31:52 INFO - Cleaning up... 09:31:52 INFO - Return code: 0 09:31:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:52 INFO - 'HOME': '/Users/cltbld', 09:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:52 INFO - 'LOGNAME': 'cltbld', 09:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:52 INFO - 'MOZ_NO_REMOTE': '1', 09:31:52 INFO - 'NO_EM_RESTART': '1', 09:31:52 INFO - 'PAGER': '/bin/cat', 09:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:52 INFO - 'PWD': '/builds/slave/test', 09:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:52 INFO - 'SHELL': '/bin/bash', 09:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:52 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:52 INFO - 'USER': 'cltbld', 09:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:52 INFO - 'XPC_FLAGS': '0x0', 09:31:52 INFO - 'XPC_SERVICE_NAME': '0', 09:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:55 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:56 INFO - Downloading docopt-0.6.1.tar.gz 09:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:57 INFO - Running setup.py install for blobuploader 09:31:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:57 INFO - Running setup.py install for requests 09:31:57 INFO - Running setup.py install for docopt 09:31:57 INFO - Successfully installed blobuploader requests docopt 09:31:57 INFO - Cleaning up... 09:31:57 INFO - Return code: 0 09:31:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:57 INFO - 'HOME': '/Users/cltbld', 09:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:57 INFO - 'LOGNAME': 'cltbld', 09:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:57 INFO - 'MOZ_NO_REMOTE': '1', 09:31:57 INFO - 'NO_EM_RESTART': '1', 09:31:57 INFO - 'PAGER': '/bin/cat', 09:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:57 INFO - 'PWD': '/builds/slave/test', 09:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:57 INFO - 'SHELL': '/bin/bash', 09:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:31:57 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:57 INFO - 'USER': 'cltbld', 09:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:57 INFO - 'XPC_FLAGS': '0x0', 09:31:57 INFO - 'XPC_SERVICE_NAME': '0', 09:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Goxh21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1BRA6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-9u8Lvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-8lCHXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-vnqHAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-_xpY9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-slKs60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:58 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-XBYDP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-KkUeZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-CXROVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-6NCdFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-JNuJdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-KuXpWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ha5PZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ss4N_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-L4dkTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:59 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-a1Icwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:59 INFO - Running setup.py install for manifestparser 09:32:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:00 INFO - Running setup.py install for mozcrash 09:32:00 INFO - Running setup.py install for mozdebug 09:32:00 INFO - Running setup.py install for mozdevice 09:32:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:00 INFO - Running setup.py install for mozfile 09:32:00 INFO - Running setup.py install for mozhttpd 09:32:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:00 INFO - Running setup.py install for mozinfo 09:32:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:00 INFO - Running setup.py install for mozInstall 09:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Running setup.py install for mozleak 09:32:01 INFO - Running setup.py install for mozlog 09:32:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Running setup.py install for moznetwork 09:32:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Running setup.py install for mozprocess 09:32:01 INFO - Running setup.py install for mozprofile 09:32:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:01 INFO - Running setup.py install for mozrunner 09:32:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:02 INFO - Running setup.py install for mozscreenshot 09:32:02 INFO - Running setup.py install for moztest 09:32:02 INFO - Running setup.py install for mozversion 09:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:02 INFO - Cleaning up... 09:32:02 INFO - Return code: 0 09:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:32:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:02 INFO - 'HOME': '/Users/cltbld', 09:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:02 INFO - 'LOGNAME': 'cltbld', 09:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:02 INFO - 'MOZ_NO_REMOTE': '1', 09:32:02 INFO - 'NO_EM_RESTART': '1', 09:32:02 INFO - 'PAGER': '/bin/cat', 09:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:02 INFO - 'PWD': '/builds/slave/test', 09:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:02 INFO - 'SHELL': '/bin/bash', 09:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:32:02 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:02 INFO - 'USER': 'cltbld', 09:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:02 INFO - 'XPC_FLAGS': '0x0', 09:32:02 INFO - 'XPC_SERVICE_NAME': '0', 09:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:02 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-V9Xg_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:02 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NZCveQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-PGD5ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:03 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)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-R3pyQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-gXbMSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:03 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)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-kVCnGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:03 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)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-bEDdX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Uf20k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-PrOKF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-6pBcAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-RBlE9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:03 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-qM5LZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:04 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-hacDZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:04 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)) 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:04 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-xNDBDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:04 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-4ZoYQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:04 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)) 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:04 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-MTYpKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:04 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-9eT80G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:07 INFO - Downloading blessings-1.6.tar.gz 09:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:07 INFO - Installing collected packages: blessings 09:32:07 INFO - Running setup.py install for blessings 09:32:07 INFO - Successfully installed blessings 09:32:07 INFO - Cleaning up... 09:32:07 INFO - Return code: 0 09:32:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:07 INFO - Reading from file tmpfile_stdout 09:32:07 INFO - Current package versions: 09:32:07 INFO - blessings == 1.6 09:32:07 INFO - blobuploader == 1.2.4 09:32:07 INFO - docopt == 0.6.1 09:32:07 INFO - functools32 == 3.2.3-2 09:32:07 INFO - jsonschema == 2.5.1 09:32:07 INFO - manifestparser == 1.1 09:32:07 INFO - mozInstall == 1.12 09:32:07 INFO - mozcrash == 1.0 09:32:07 INFO - mozdebug == 0.1 09:32:07 INFO - mozdevice == 0.49 09:32:07 INFO - mozfile == 1.2 09:32:07 INFO - mozhttpd == 0.7 09:32:07 INFO - mozinfo == 0.9 09:32:07 INFO - mozleak == 0.1 09:32:07 INFO - mozlog == 3.4 09:32:07 INFO - moznetwork == 0.27 09:32:07 INFO - mozprocess == 0.25 09:32:07 INFO - mozprofile == 0.28 09:32:07 INFO - mozrunner == 6.13 09:32:07 INFO - mozscreenshot == 0.1 09:32:07 INFO - mozsystemmonitor == 0.3 09:32:07 INFO - moztest == 0.8 09:32:07 INFO - mozversion == 1.4 09:32:07 INFO - psutil == 3.1.1 09:32:07 INFO - requests == 1.2.3 09:32:07 INFO - wsgiref == 0.1.2 09:32:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:32:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:07 INFO - 'HOME': '/Users/cltbld', 09:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:07 INFO - 'LOGNAME': 'cltbld', 09:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:07 INFO - 'MOZ_NO_REMOTE': '1', 09:32:07 INFO - 'NO_EM_RESTART': '1', 09:32:07 INFO - 'PAGER': '/bin/cat', 09:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:07 INFO - 'PWD': '/builds/slave/test', 09:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:07 INFO - 'SHELL': '/bin/bash', 09:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:32:07 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:07 INFO - 'USER': 'cltbld', 09:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:07 INFO - 'XPC_FLAGS': '0x0', 09:32:07 INFO - 'XPC_SERVICE_NAME': '0', 09:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 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)) 09:32:08 INFO - Cleaning up... 09:32:08 INFO - Return code: 0 09:32:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:08 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')]} 09:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:08 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')]} 09:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:08 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 0x1103b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed9bd99150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, '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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:32:08 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 09:32:08 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 09:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:08 INFO - 'HOME': '/Users/cltbld', 09:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:08 INFO - 'LOGNAME': 'cltbld', 09:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:08 INFO - 'MOZ_NO_REMOTE': '1', 09:32:08 INFO - 'NO_EM_RESTART': '1', 09:32:08 INFO - 'PAGER': '/bin/cat', 09:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:08 INFO - 'PWD': '/builds/slave/test', 09:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:08 INFO - 'SHELL': '/bin/bash', 09:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:32:08 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:08 INFO - 'USER': 'cltbld', 09:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:08 INFO - 'XPC_FLAGS': '0x0', 09:32:08 INFO - 'XPC_SERVICE_NAME': '0', 09:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:08 INFO - Cleaning up... 09:32:08 INFO - Return code: 0 09:32:08 INFO - Running post-action listener: _resource_record_post_action 09:32:08 INFO - Running post-action listener: _start_resource_monitoring 09:32:08 INFO - Starting resource monitoring. 09:32:08 INFO - [mozharness: 2017-03-30 16:32:08.374535Z] Finished create-virtualenv step (success) 09:32:08 INFO - [mozharness: 2017-03-30 16:32:08.375467Z] Running install step. 09:32:08 INFO - Running pre-action listener: _resource_record_pre_action 09:32:08 INFO - Running main action method: install 09:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:08 INFO - Reading from file tmpfile_stdout 09:32:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:08 INFO - Reading from file tmpfile_stdout 09:32:08 INFO - Output received: 09:32:08 INFO - Usage: mozinstall [options] installer 09:32:08 INFO - Options: 09:32:08 INFO - -h, --help show this help message and exit 09:32:08 INFO - -d DEST, --destination=DEST 09:32:08 INFO - Directory to install application into. [default: 09:32:08 INFO - "/builds/slave/test"] 09:32:08 INFO - --app=APP Application being installed. [default: firefox] 09:32:08 INFO - mkdir: /builds/slave/test/build/application 09:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:32:28 INFO - Reading from file tmpfile_stdout 09:32:28 INFO - Output received: 09:32:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:32:28 INFO - Running post-action listener: _resource_record_post_action 09:32:28 INFO - [mozharness: 2017-03-30 16:32:28.986352Z] Finished install step (success) 09:32:28 INFO - [mozharness: 2017-03-30 16:32:28.986508Z] Running run-tests step. 09:32:28 INFO - Running pre-action listener: _resource_record_pre_action 09:32:28 INFO - Running main action method: run_tests 09:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:29 INFO - 2017-03-30 09:32:29.000 screenresolution[1618:7349] starting screenresolution argv=screenresolution get 09:32:29 INFO - 2017-03-30 09:32:29.003 screenresolution[1618:7349] Display 0: 1600x1200x32@60 09:32:29 INFO - 2017-03-30 09:32:29.009 screenresolution[1619:7352] starting screenresolution argv=screenresolution list 09:32:29 INFO - Available Modes on Display 0 09:32:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:29 INFO - Intel Iris: 09:32:29 INFO - Chipset Model: Intel Iris 09:32:29 INFO - Type: GPU 09:32:29 INFO - Bus: Built-In 09:32:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:29 INFO - Vendor: Intel (0x8086) 09:32:29 INFO - Device ID: 0x0a2e 09:32:29 INFO - Revision ID: 0x0009 09:32:29 INFO - Displays: 09:32:29 INFO - Display: 09:32:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:29 INFO - Display Serial Number: 1600x1200 60 09:32:29 INFO - Main Display: Yes 09:32:29 INFO - Mirror: Off 09:32:29 INFO - Online: Yes 09:32:29 INFO - Rotation: Supported 09:32:29 INFO - Return code: 0 09:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:29 INFO - Python 2.7.3 09:32:29 INFO - Return code: 0 09:32:29 INFO - grabbing minidump binary from tooltool 09:32: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')]} 09:32:29 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 0x110193d30>, '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 0x1100badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110188be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:29 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 09:32:29 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 09:32:29 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 09:32:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:29 INFO - Return code: 0 09:32:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:29 INFO - ENV: RUST_BACKTRACE is now 1 09:32:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 09:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --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 09:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwBK0ZTfGP/Render', 09:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:29 INFO - 'HOME': '/Users/cltbld', 09:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:29 INFO - 'LOGNAME': 'cltbld', 09:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:29 INFO - 'MOZ_NO_REMOTE': '1', 09:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:29 INFO - 'NO_EM_RESTART': '1', 09:32:29 INFO - 'PAGER': '/bin/cat', 09:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:29 INFO - 'PWD': '/builds/slave/test', 09:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:29 INFO - 'RUST_BACKTRACE': '1', 09:32:29 INFO - 'SHELL': '/bin/bash', 09:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4NNRmraaTr/Listeners', 09:32:29 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:29 INFO - 'USER': 'cltbld', 09:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:29 INFO - 'XPC_FLAGS': '0x0', 09:32:29 INFO - 'XPC_SERVICE_NAME': '0', 09:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 09:32:29 INFO - mozversion application_buildid: 20170330080007 09:32:29 INFO - mozversion application_changeset: 3364cc17988c013c36f2a8123315db2855393011 09:32:29 INFO - mozversion application_display_name: Nightly 09:32:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:29 INFO - mozversion application_name: Firefox 09:32:29 INFO - mozversion application_remotingname: firefox 09:32:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:32:29 INFO - mozversion application_vendor: Mozilla 09:32:29 INFO - mozversion application_version: 55.0a1 09:32:29 INFO - mozversion platform_buildid: 20170330080007 09:32:29 INFO - mozversion platform_changeset: 3364cc17988c013c36f2a8123315db2855393011 09:32:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:32:29 INFO - mozversion platform_version: 55.0a1 09:32:29 INFO - using testdate: 1490891549 09:32:29 INFO - actual date: 1490891549 09:32:29 INFO - starting webserver on 'localhost:49240' 09:32:29 INFO - SUITE-START | Running 2 tests 09:32:29 INFO - TEST-START | dromaeo_css 09:32:29 INFO - operating with platform_type : mac_ 09:32:29 INFO - Initialising browser for dromaeo_css test... 09:32:29 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpqTX_1q/profile http://localhost:49240/getInfo.html) 09:32:32 INFO - PID 1629 | __metrics Screen width/height:1600/1200 09:32:32 INFO - PID 1629 | colorDepth:24 09:32:32 INFO - PID 1629 | Browser inner width/height: 1024/689 09:32:32 INFO - PID 1629 | __metrics 09:32:32 ERROR - PID 1629 | 1490891552111 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:32:32 INFO - PID 1629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:32:32 INFO - PID 1629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:32:33 INFO - TEST-INFO | 1629: exit 0 09:32:33 INFO - Browser initialized. 09:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:33 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpqTX_1q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:45 INFO - PID 1631 | RSS: Main: 234115072 09:32:45 INFO - PID 1631 | 09:35:49 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:35:51 INFO - PID 1631 | RSS: Main: 363675648 09:35:51 INFO - PID 1631 | 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | A coding exception was thrown and uncaught in a Task. 09:37:35 INFO - PID 1631 | 09:37:35 ERROR - PID 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:35 INFO - PID 1631 | Full stack: 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | A coding exception was thrown and uncaught in a Task. 09:37:35 INFO - PID 1631 | 09:37:35 ERROR - PID 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:35 INFO - PID 1631 | Full stack: 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | A coding exception was thrown and uncaught in a Task. 09:37:35 INFO - PID 1631 | 09:37:35 ERROR - PID 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:35 INFO - PID 1631 | Full stack: 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | ************************* 09:37:35 INFO - PID 1631 | A coding exception was thrown and uncaught in a Task. 09:37:35 INFO - PID 1631 | 09:37:35 ERROR - PID 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:35 INFO - PID 1631 | Full stack: 09:37:35 INFO - PID 1631 | ************************* 09:38:54 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:38:54 INFO - PID 1631 | RSS: Main: 366415872 09:38:54 INFO - PID 1631 | 09:41:56 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:41:57 INFO - PID 1631 | RSS: Main: 337383424 09:41:57 INFO - PID 1631 | 09:44:59 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:45:00 INFO - PID 1631 | RSS: Main: 325201920 09:45:00 INFO - PID 1631 | 09:48:03 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:48:04 INFO - PID 1631 | RSS: Main: 332701696 09:48:04 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:51:08 INFO - PID 1631 | RSS: Main: 470437888 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | __start_tp_report 09:51:08 INFO - PID 1631 | _x_x_mozilla_page_load 09:51:08 INFO - PID 1631 | _x_x_mozilla_page_load_details 09:51:08 INFO - PID 1631 | |i|pagename|runs| 09:51:08 INFO - PID 1631 | |0;dojo.html;3082.7379672727816;3432.639572844851;3484.756067075303;3502.492138639897;3545.078279647291;6070.484008859252;6110.892920173598;6221.346758590345;6231.501479881605;6235.625862448251;6151.108089327045;6160.84597885052;6227.719752611132;6239.532035097374;6259.280182778982;5341.145416733323;5481.616286859921;5538.086215774402;5556.722163891809;5682.687452190128;5575.275214222166;5642.322921249435;5642.548596112301;5653.208550802891;5658.575606829484;5402.108652072403;5481.985832620938;5499.257600223958;5508.228847961289;5571.888562228753;5595.048841696921;5602.13166959122;5603.7478313475995;5612.663240205601;5644.576656750719;5028.396592408898;5501.422350653163;5512.200730894038;5531.3362396512675;5533.474319939599;5571.637843540157;6176.752929882799;6186.9381306186815;6190.257169139687;6204.5967012144065;6330.303666596671;6381.8085457436355;6385.329540398284;6426.839329016812;6535.836604084889;6529.34706529348;6623.105880706091;6662.167229096363;6667.433268172211;6680.231773346079;6242.001279795211;6307.148534947769;6322.873542529123;6329.746810127589;6338;6520.543561950618;6609.239937407164;6632.767853125118;6822.897656535156;6831.180258369028;6384.170057892537;6475.546711730133;6481.902771458432;6483.09236706852;6519.608823470607;6551.639659818756;6595.901061484082;6615.470762339001;6616.735330586819;6625.469962402996;6337.619742815354;6413.230412350455;6517.055027021055;6519.3154718754795;6537.542372033912;6371.203599550056;6374.426301632875;6376.266729326094;6392.4886009119155;6398.680065996681;5156.123459011977;5291.550218231417;5335.039692855245;5338.092524270884;5356.223347614574;4673.649476289292;4676.883077923079;4684.484706682262;4685.554872287127;4689.929651055237;6329.082283069021;6329.3354197809485;6336.04959256106;6343.746250150035;6358.586691865106;6400.679966001681;6470.9029364559565;6546.607203567706;6628.834279142963;6652.101966234593;6336.20797400325;6352.523560732871;6364.490840732823;6369.9681501592195;6378.394052564999;5765.567582431335;5800.6809625471005;5806.390329015477;5816.767329306865;5850.034744267148;6562.704678289584;6641.136652235178;6649.434798042221;6796.422304104207;6853.9314606853295;6557.409833114852;6617.305182955721;6631.038499417654;6631.469482441489;6651.0023496475915;6779.525433219627;6805.625690587065;6816.216135144522;6816.318368163343;6830.316968303328;5305.389880163831;6448.484121270381;6498;6504.38208370204;6505;5569;6450.032495125769;6462.289148193789;6531.281559028408;6597.307282735435;6421.165248517663;6432.485401167802;6438.774642887476;6460.967695161494;6467.870642587028;6343.936560634519;6401.263854656588;6413.326600706859;6422.036694495863;6429.2284925807335;6136.059583474799;6237.5321850860455;6237.937620623918;6294.370562943852;6367.936320636734;5420.647657902224;6422.486201103807;6424.743010279536;6430.549861509649;6435.613863168225;6328.177336946352;6354.90467642995;6356.173697419306;6357;6395.488360931207 09:51:08 INFO - PID 1631 | |1;ext.html;687.6355531568267;801.1787917384681;814.6944895664124;1113.7661091170853;1137.8691450483198;28121.718782812164;37951.62048379515;38376.23247535047;38389.808050959706;38394.84815455534;39807;40228.59771402285;40349.7982510087;40358.991025224386;40376;6056.697165141747;8405.159484051606;8538.658453661847;8544.487330760143;8561.614727337253;5317.2821669074565;6250.0624906263965;6252.531060170482;6264.154339164223;6274.498040156776;8386.245237928586;8539.871901921477;8557.400981931267;8570.142985701412;8573.828523429527;8499.830003399928;8556.657733690645;8561.529115898624;8583.699570515002;8601.354898382584;8310.044344900292;8393.53835539045;8398.03422606402;8404.873926891101;8412.495250285003;37539.4369084464;37711.43432848509;37772.24455510902;37788.62211377879;37902.43146352807;2993.4162838246552;3002.3463631371287;3013.1563162314587;3026.228311780482;3032.6057612510454;20774.58450830975;27385.31536711578;27555.86222068897;27787.58318625202;28203.43593128126;2766.5573508238786;2803.8457884816326;2804.9439011219865;2808.69104398516;2892.3492214251837;312956;313108;313889;313938;315203;49064.528064158134;49163.50836491661;49209.26186107211;49247;49338.75330623324;52552.73723631357;52589.47410525846;52684.47315526796;52758.736206318725;52806.871072405505;14447.566573002616;14575.562733118024;14582.708345833023;14590.927045364706;14606.34271457787;12028.458719357475;12037.759244815055;12039.458224379923;12059.57791477294;12066.939665301617;10178.338408003456;10351.482425878676;10366.61567839386;11298.943505282421;12305.56930507428;5262.578993680573;5433.375161856358;5434.67391956476;5436.809711660073;5437.782488700487;956.2588993529931;970.6748239339901;973.454865275448;987.1559816357055;1001.3140998416109;24183.879080604485;24216.87891560531;24237.151699690425;24260.636090458658;24296.51406971886;1074.1245884604023;1083.5340803454433;1110.7389763405592;1115.55935405516;1119.9664010079712;9528.618855245713;12267.877321226852;12306.323152226714;12340.259584424963;12357.876421235673;425110;425910.9271258292;426121;426144;426177;43172;52618.47381526212;52794.47205527972;53045.469545304826;53070.73464632652;1372.6568357910523;1446.8697817196503;1457.3806132393902;1462.5904746671163;1495.3046833222386;22911.19810806614;23447;23607.527849443257;23612;23704.525909482058;36870.631293687795;36899.446508302935;36907.63092369042;36939.99999999892;36967.44548831823;394253.0287348545;397776.0222397739;400575;400613.99693002517;400662;28865.85567072151;29538.55692164662;29657.5551366734;29721.851390742908;32513.512297316032;31236;35331.64668353284;37241;43665.34501982536;44342.77828610836;28598.285042873264;29160.730442954868;29389;30388.936387226342;30726;23597.410064748517;27098.547899327055;29885.70114298829;30076.5488517668;30241.395172095996;31722.68277317197;35424.291514169054;36664;36716.89849304525;36806.81596592107;29314.56028159622;29725;29732.702672973857;30602.540961885144;30809;22671.919948725612;23739.881300593388;24177.615133880918;24328.27015189547;24339.878300608383 09:51:08 INFO - PID 1631 | |2;jquery.html;420.657164411005;425.25155725922366;427.7754179055994;430.2470676316446;444.63984172820017;19715.01424928755;21529.784702152974;22235.22176723815;22274.88862555687;22275.44311392216;21279.787202127973;22312.979579594623;22797.886010569964;22966.655500167468;23255;6158.445739883421;6351.237851457809;6355.555111142222;6370.649614271213;6371.553991220617;3732.328180927432;5424.457554244584;6283.654399008075;6344.33384494626;6348.9682551587175;5989.251343582052;6307.211598550181;6311.779087731908;6318.684065796715;6344.080108384282;6111.113888486167;6224.564280500367;6243.313235544109;6246.906296405512;6248.968920128196;5328.520433160996;5481.698506582176;5529.806456774034;5540.36285827131;5563.499285064364;26427;26498.675066246804;26548.73451265482;26553.73446265532;27026.864865675547;2045.1819272291054;2052.127845665586;2057.4753437873246;2063.5253891604866;2079.334612923865;15508.379664813394;15643.608909777233;15989.440369587124;16310.918445407697;16483.58791030222;1590.624110144728;1598.8081430228447;1607.5097095385902;1646.7941507311587;1651.4797838680777;132889;139318.30340848232;139526;140457.29771351078;141968.12446257533;11592.6522204334;11848.526058957545;11926.403679816149;11945.462454189583;12053.397330133634;15835.762463562825;15835.841641583667;15841.603959901095;15857.762133568005;15864.682706345808;8555.871661925075;8726.69456569017;8739.912600874037;8779.956100219459;8914.86627700585;6865.656717164122;8421.19998600132;8585.527795971157;8588.699395521127;8612.65549378018;6136.386361363828;7280.216346994915;7448.73929412468;7460.3658689012045;7484.139323977813;2090.278853795438;2104.3266154830308;2104.863183893042;2107.9578408432053;2113.1230539326316;937.8499440089553;947.1049857884371;948.8102379524123;962.6823148361024;966.3622009473713;18953.620927581647;20562.074706638236;20621.89689051545;20622;20991.895040524698;857.8498762716499;893.5085702863399;941.275218082073;983.8524221366853;1015.1574193419444;8759;9535.994882149515;10330.741731456774;10787.352758834495;10814.89185108139;619323;635270;635439;642091;648270;159164;160287;162112;162416;165828;1159.2979425160972;1186.2348785033605;1217.0567809947186;1225.185251807556;1225.2587184753097;17707.557311067325;17824.643507129524;17945.282188712827;18219.45341639753;18222.817771822112;47553.81115472141;61370.69314653398;61593.15220543391;62845.05732413926;63222.68388658027;85749.71375429498;85980.57009714912;87996.68004980059;88259;88324.55837720771;7490.325870671666;8111.918880811117;8560.614772335135;8696.739097827076;8902.154295341932;13526.729465410834;13630.04589678713;13641.454341826216;14056.578302650936;14080.788788168391;11719.296842189497;11895.10786691019;11905.880941190477;12064.517419303129;12113.365990202728;10862.076723478593;11000.339979601249;11088.778224435628;11338.659840204808;11425.771484570096;11581.652550423501;12031.578894738643;12068.215565988183;12095.153339266168;12106.75786484283;8477.152284771473;8503.34389999083;8737.126287371466;8855.60149793248;8874.423162494417;11538.307701537935;11729.589464368706;12042.819357709466;12111;12132.696682583006 09:51:08 INFO - PID 1631 | |3;mootools.html;1188.2870277833297;1273.4587800184922;1407.46516323797;1430.8211473565805;1442.1131004432275;9237.49193794341;9677.61289548418;10690.946545267283;10980.945095274512;11029.172812039089;10639.78720425591;10763.62327318544;10767.83848242277;10768.892311076886;10771.730706732336;2402.3994001499627;2452.6443665668467;2453;2456.45957889265;2459.5203935232635;2090.0385822521584;2172.31572054803;2173.9021744021557;2175.8476906616543;2177.8693278403266;2113.2814842953394;2289.8168146548237;2362.9291121266388;2377.4888398994212;2398.388410955204;2386.4153282445736;2393.222202784102;2393.4495066134887;2393.904243830245;2394.5570069537193;2365.7634236576205;2403.2189538399916;2404.206611818096;2406.31420045288;2406.9157579484636;10654.360738355646;10775.191860610406;10786.406747628876;10804.513796879159;10851.348919064882;6105.969470152665;6338.683065846689;6344.016677415008;6345.8413539661415;6353.777617783356;3667.889963301105;3842.53889533255;4007.6593489553134;4019.356902895552;4023.97988010061;1125.819868820993;1160.628598848369;1185.2473679213674;1211.9757604847853;1215.2465471407784;12398;15116.773248401283;15248.466303679317;15270.618234544225;15276.38894444221;4632.953670463319;4633.328167415706;5263.315768950089;5612.017896868113;5670.865826834567;12684.365781711062;12684.365781711062;12686.682832929066;12694.80957785634;12698.42857071434;5597.412271711493;6087.726052327655;6374.075759014917;6374.681265936684;6375.681215939185;5540.556755459554;5640.266765320482;5675.631083979528;5698.287714035746;5907.911381329283;3355.8925554566936;4213.768242746678;4351.282038463618;4373.518912919576;4424.159409712144;1245.8940990015951;1289.265118882247;1289.5615490733196;1292.844858616972;1293.495536740672;617.7528988404674;686.7699320727512;743.624469642827;757.5946868425476;770.6570576093696;6240.31356550779;6325.936740632535;6506.479481641457;6615.073889655452;6716.46268298535;879.3317079019863;881.131915614683;909.6725178935578;910.1808372464702;917.6925729880564;3569.446735755962;3846.9807650962125;3954.683625310026;4026.9597304026584;4158.2307273154565;13232.867671323356;13854.16874987504;13876.653083672787;13885.889128869667;13903.026788124937;13743.106698064594;13745.312734363442;13757.10578812374;13759.656008599864;13765.311734413239;9828.31201815866;10072.294939354322;10253.897461025294;10267.897321026694;10277.126444252322;6513.869722605426;6528.347165283433;6536.869262614816;6754.527183097136;6798.184217893884;12052.337121458053;12472.126951113334;12538.247705137721;12611.369431528203;12653.367331633197;12204.816927745913;12592.370381481072;12598.748025039264;12606.117571769888;12624.053196010153;8858.29133669292;9006.24378246262;9067.68263110788;9078.727638170865;9085.636574536944;9785.608575656894;9990.750231243986;10136.594536218468;10165.54255058539;10270.024347686958;6633.502487313374;7905.920940790748;7980.4812687175145;8001.919980800118;8063.596820159086;8361.247487726134;8389.370797190113;8390.874136887829;8422.452540584842;8492.830143396974;7761;7896.565688887166;7913;7913.9604301978125;7915.168907264654;5750.856228594152;5791.102379131242;5936.910946335895;6017.187679663364;6019.6990150493175;5358.383785864676;5630.183623374506;5701.600887937971;5834.27071616048;5968.76124954997 09:51:08 INFO - PID 1631 | |4;prototype.html;387.4304771985182;393.9842406303748;397.38008706417975;400.05187705138826;401.24966312994684;5883.352831188566;5921.5558833087525;5955.404459554043;5962.314333851608;6069.57512974092;5186.299849520314;5763.221965034719;5767.269819054278;5851.552819856907;5928.762849486026;3878.9030274243228;4375.453068366454;4393.945453091267;4519.457665080186;4532.796024178903;3923.431102490137;4456.353828694837;4478.328250762395;4528.796204170803;4530.637548996089;3533;3873.573906870242;4488.461384633849;4497.662675299349;4501.942043619747;3944.757401418544;4041.6362527372385;4497.910041799162;4506.008678090815;4547.226971414867;3832.3485007548784;3836.731428799957;3852.84588616455;3853.9421908671393;3880.1463677990796;5322.855586049018;5339.252504649352;5746.54027677789;5778.566607504454;5809.390014048507;5018.899622007576;5167.612429067835;5167.612429067835;5199.454057323965;5213.4525874782985;6267.5299352548745;6287.245530536365;6327.4938004959495;6334.448060225228;6346.397976829524;3561.821908904544;3599.3701102307255;3613.7831730096277;4307.892302692427;4318.287482565373;6094.96952515239;6438.227412710505;6559.34406559345;6686.531942764009;6748.190217173921;3640.5449318835144;3815.5802861685193;4373.759443230653;4374.496932852699;4381.014271788886;3799.8790356844797;4425.114977004612;4431.844885429059;4449.57729015743;4453.3320001999955;5014.974925125351;5131.179011358165;5151.201563757623;5168.147255702767;5211.478852114834;5219.739013049332;6350;6363.9045414318825;6366.633366633367;6387.636574467384;1494.880409567232;1515.8256800468087;1516.196415899575;1519.56692342684;1523.4210999819998;855.4054931822324;952.7379970508166;991.2020823237364;996.3175224970918;1003.8694969653898;6038.849028774228;6040.637561746309;6044.821259854286;6048.90926636092;6049.818505444844;5703.54371650267;6548.508861835381;6577.309382514863;6590;6593.80218593443;5159.561437277874;5795.565332600072;5972.492338151305;6143.877122457526;6150.292716337589;2766.681831589353;3129.6713845046397;3329;3435.811030393302;3446.3802013053823;6248;6629.149465729783;6777.000784768505;6783.879415468939;6860.010596926835;6388.552801303864;6491.610503369813;6501.934980650133;6662.7334906604165;6768.796936091925;5915.349311575637;6231.040255878422;6244.59583060525;6463.709133089116;6700.631465269457;5505.201745746925;6321.557490975588;6612.272650008399;6640.302768209269;6644.46844252449;6179.660118693515;6504.349565043647;6506.739730410731;6513.804585862432;6527.967360163168;5711.9718450679275;6818.386345228953;6853.520253582202;6854.485913556404;6878.38094571491;3850.6726928211415;4286.357046442934;4327.545607711145;4333.458317710286;4353.891152721208;5937.317208521075;6494.707738151889;6593.3736295051895;6606.867862642816;6620.702068407029;6080.348533371885;6332.5303131779565;6349.777757778455;6418.518611104092;6445.4199122079235;5868.94131058684;6317.747290108528;6328.12070507927;6356.266805273776;6359.459445946961;5216.66091704038;5426.430224826337;5546.813729163246;5997.820065398045;6241.251049874044;4851.1412995082455;6246.812595622138;6247.562670613013;6289.685515724287;6339.098270518918;5164.845054648366;5347.144456886879;6136.386361364007;6185.165002724574;6231.626102433868 09:51:08 INFO - PID 1631 | |5;yui.html;713.2119008495615;719.8704233238016;731.5644759437381;734.6767422334173;738.0958326050588;15603.84396156038;16485.67028659426;16488;16505.669886602274;16540.503784886456;15161.620959476018;15267.770983435257;15290.770638440432;15325.846741532581;16492.83507164928;967.9419234845914;992.3946392700446;1006.6174853555639;1008.0222184481079;1010.9342892711987;1011.3931641015405;1013.5793645637052;1013.944233067181;1014.8934361891969;1015.8222311095528;1016.4054028393399;1063.6064656077262;1064.3826580583282;1066.3761699405818;1066.9413182274973;1054.140875186724;1059.8781140168903;1060.2525219720078;1062.9255952083358;1063.1441689439998;778.0507780507828;781.929626333627;784.5371230973695;784.9843003139962;787.1734678587518;16200.270987805578;16223.756643650355;16364.34542618294;16405.58986025347;17051.403200887908;14656.560303190921;14684.192369419678;14711.779323310158;14827.54351647814;14832.851671483255;10826.783464330749;10864.185186111074;11085.944570277177;11154.051905587954;11317.207795454322;3030;3086.737627301682;3140.764442666809;3156.8421578921193;3201.5677883485664;95758.0424195756;108238;109592;111795;112039;9369.48467834262;10874.510647020903;11584.304941703525;11603.187776855539;11906.345151016603;20735.481612959797;23057.42356441069;25158.24525264245;25165;25173.24480265595;7992.000999875016;8008.71969481077;8012.998375203099;8097.2307630774985;8097.878531822027;3502.9124271893406;3994.7403418777685;4222.978885105616;4246.617804397557;4278.935815962763;3928.332183528807;4081.285774989365;4184.28867092595;4195.160967806451;4667.206574882278;850.0904032685091;871.7994861181963;897.1297841094129;897.4974014551872;899.3120262998812;478.4043865387545;544.2000259618354;558.9720513974285;561.898858205527;576.331649053778;9966.40201587907;10222.6422075227;10269.281150319406;10322.174226061898;10418.037854763768;568.692905830855;576.1703147467633;591.5681552466723;595.761695321866;599.6731781179268;4448.644108471314;4628.63455280693;4633.675642705046;4668.789904454307;4684.344191813149;95812.52093739486;106429;107166;107759.46120269349;108708.4564577172;56816;85923;88277.67583486253;89175.10824891798;90332;49219.75390123098;60328.39671603315;77227.22772772313;77273;77836;10647.94676026615;10672.252942294275;10729.551510545976;10928.836067458835;10935.453227338443;36356.81821590875;36995.44506832454;37627.623723762415;37958.810205948794;38253.85238442851;47657.76171119122;48859;49121.263181051596;49600;49988.75005624948;12758.48966041348;12985;13076.215427074025;13090.803637945248;13738.793918091436;14083.436662533793;14306.213158275978;14408.351624176728;14466.566003019927;14570.34433450476;13104.475820967054;13302.93348533251;13410.365369539026;13723.439583261004;14021.29893505341;13342.599722007966;13387.078290591415;13389.263590502616;13559.661008474866;13654;13039.739205216032;13078.738425231251;13093.25041741245;13240.668983275495;13979.161250325013;9520.823379518899;9720.222382209266;9885.403287471072;9996;10808.351498910091;9935.701928942144;10182.49087545605;10332.948335258276;11123.777524449304;11392.772144557228 09:51:08 INFO - PID 1631 | __end_tp_report 09:51:08 INFO - PID 1631 | __start_cc_report 09:51:08 INFO - PID 1631 | _x_x_mozilla_cycle_collect,1489 09:51:08 INFO - PID 1631 | __end_cc_report 09:51:08 INFO - PID 1631 | __startTimestamp1490892668576__endTimestamp 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | ------- Summary: start ------- 09:51:08 INFO - PID 1631 | Number of tests: 6 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#0] dojo.html Cycles:165 Average:6088.00 Median:6354.90 stddev:689.03 (10.8%) stddev-sans-first:649.56 09:51:08 INFO - PID 1631 | Values: 3082.7 3432.6 3484.8 3502.5 3545.1 6070.5 6110.9 6221.3 6231.5 6235.6 6151.1 6160.8 6227.7 6239.5 6259.3 5341.1 5481.6 5538.1 5556.7 5682.7 5575.3 5642.3 5642.5 5653.2 5658.6 5402.1 5482.0 5499.3 5508.2 5571.9 5595.0 5602.1 5603.7 5612.7 5644.6 5028.4 5501.4 5512.2 5531.3 5533.5 5571.6 6176.8 6186.9 6190.3 6204.6 6330.3 6381.8 6385.3 6426.8 6535.8 6529.3 6623.1 6662.2 6667.4 6680.2 6242.0 6307.1 6322.9 6329.7 6338.0 6520.5 6609.2 6632.8 6822.9 6831.2 6384.2 6475.5 6481.9 6483.1 6519.6 6551.6 6595.9 6615.5 6616.7 6625.5 6337.6 6413.2 6517.1 6519.3 6537.5 6371.2 6374.4 6376.3 6392.5 6398.7 5156.1 5291.6 5335.0 5338.1 5356.2 4673.6 4676.9 4684.5 4685.6 4689.9 6329.1 6329.3 6336.0 6343.7 6358.6 6400.7 6470.9 6546.6 6628.8 6652.1 6336.2 6352.5 6364.5 6370.0 6378.4 5765.6 5800.7 5806.4 5816.8 5850.0 6562.7 6641.1 6649.4 6796.4 6853.9 6557.4 6617.3 6631.0 6631.5 6651.0 6779.5 6805.6 6816.2 6816.3 6830.3 5305.4 6448.5 6498.0 6504.4 6505.0 5569.0 6450.0 6462.3 6531.3 6597.3 6421.2 6432.5 6438.8 6461.0 6467.9 6343.9 6401.3 6413.3 6422.0 6429.2 6136.1 6237.5 6237.9 6294.4 6367.9 5420.6 6422.5 6424.7 6430.5 6435.6 6328.2 6354.9 6356.2 6357.0 6395.5 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#1] ext.html Cycles:180 Average:51016.27 Median:35377.97 stddev:101439.90 (286.7%) stddev-sans-first:101654.09 09:51:08 INFO - PID 1631 | Values: 687.6 801.2 814.7 1113.8 1137.9 28121.7 37951.6 38376.2 38389.8 38394.8 39807.0 40228.6 40349.8 40359.0 40376.0 6056.7 8405.2 8538.7 8544.5 8561.6 5317.3 6250.1 6252.5 6264.2 6274.5 8386.2 8539.9 8557.4 8570.1 8573.8 8499.8 8556.7 8561.5 8583.7 8601.4 8310.0 8393.5 8398.0 8404.9 8412.5 37539.4 37711.4 37772.2 37788.6 37902.4 2993.4 3002.3 3013.2 3026.2 3032.6 20774.6 27385.3 27555.9 27787.6 28203.4 2766.6 2803.8 2804.9 2808.7 2892.3 312956.0 313108.0 313889.0 313938.0 315203.0 49064.5 49163.5 49209.3 49247.0 49338.8 52552.7 52589.5 52684.5 52758.7 52806.9 14447.6 14575.6 14582.7 14590.9 14606.3 12028.5 12037.8 12039.5 12059.6 12066.9 10178.3 10351.5 10366.6 11298.9 12305.6 5262.6 5433.4 5434.7 5436.8 5437.8 956.3 970.7 973.5 987.2 1001.3 24183.9 24216.9 24237.2 24260.6 24296.5 1074.1 1083.5 1110.7 1115.6 1120.0 9528.6 12267.9 12306.3 12340.3 12357.9 425110.0 425910.9 426121.0 426144.0 426177.0 43172.0 52618.5 52794.5 53045.5 53070.7 1372.7 1446.9 1457.4 1462.6 1495.3 22911.2 23447.0 23607.5 23612.0 23704.5 36870.6 36899.4 36907.6 36940.0 36967.4 394253.0 397776.0 400575.0 400614.0 400662.0 28865.9 29538.6 29657.6 29721.9 32513.5 31236.0 35331.6 37241.0 43665.3 44342.8 28598.3 29160.7 29389.0 30388.9 30726.0 23597.4 27098.5 29885.7 30076.5 30241.4 31722.7 35424.3 36664.0 36716.9 36806.8 29314.6 29725.0 29732.7 30602.5 30809.0 22671.9 23739.9 24177.6 24328.3 24339.9 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#2] jquery.html Cycles:180 Average:38539.15 Median:22555.43 stddev:107324.01 (475.8%) stddev-sans-first:107586.92 09:51:08 INFO - PID 1631 | Values: 420.7 425.3 427.8 430.2 444.6 19715.0 21529.8 22235.2 22274.9 22275.4 21279.8 22313.0 22797.9 22966.7 23255.0 6158.4 6351.2 6355.6 6370.6 6371.6 3732.3 5424.5 6283.7 6344.3 6349.0 5989.3 6307.2 6311.8 6318.7 6344.1 6111.1 6224.6 6243.3 6246.9 6249.0 5328.5 5481.7 5529.8 5540.4 5563.5 26427.0 26498.7 26548.7 26553.7 27026.9 2045.2 2052.1 2057.5 2063.5 2079.3 15508.4 15643.6 15989.4 16310.9 16483.6 1590.6 1598.8 1607.5 1646.8 1651.5 132889.0 139318.3 139526.0 140457.3 141968.1 11592.7 11848.5 11926.4 11945.5 12053.4 15835.8 15835.8 15841.6 15857.8 15864.7 8555.9 8726.7 8739.9 8780.0 8914.9 6865.7 8421.2 8585.5 8588.7 8612.7 6136.4 7280.2 7448.7 7460.4 7484.1 2090.3 2104.3 2104.9 2108.0 2113.1 937.8 947.1 948.8 962.7 966.4 18953.6 20562.1 20621.9 20622.0 20991.9 857.8 893.5 941.3 983.9 1015.2 8759.0 9536.0 10330.7 10787.4 10814.9 619323.0 635270.0 635439.0 642091.0 648270.0 159164.0 160287.0 162112.0 162416.0 165828.0 1159.3 1186.2 1217.1 1225.2 1225.3 17707.6 17824.6 17945.3 18219.5 18222.8 47553.8 61370.7 61593.2 62845.1 63222.7 85749.7 85980.6 87996.7 88259.0 88324.6 7490.3 8111.9 8560.6 8696.7 8902.2 13526.7 13630.0 13641.5 14056.6 14080.8 11719.3 11895.1 11905.9 12064.5 12113.4 10862.1 11000.3 11088.8 11338.7 11425.8 11581.7 12031.6 12068.2 12095.2 12106.8 8477.2 8503.3 8737.1 8855.6 8874.4 11538.3 11729.6 12042.8 12111.0 12132.7 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#3] mootools.html Cycles:180 Average:6723.37 Median:3462.67 stddev:4193.34 (121.1%) stddev-sans-first:4184.47 09:51:08 INFO - PID 1631 | Values: 1188.3 1273.5 1407.5 1430.8 1442.1 9237.5 9677.6 10690.9 10980.9 11029.2 10639.8 10763.6 10767.8 10768.9 10771.7 2402.4 2452.6 2453.0 2456.5 2459.5 2090.0 2172.3 2173.9 2175.8 2177.9 2113.3 2289.8 2362.9 2377.5 2398.4 2386.4 2393.2 2393.4 2393.9 2394.6 2365.8 2403.2 2404.2 2406.3 2406.9 10654.4 10775.2 10786.4 10804.5 10851.3 6106.0 6338.7 6344.0 6345.8 6353.8 3667.9 3842.5 4007.7 4019.4 4024.0 1125.8 1160.6 1185.2 1212.0 1215.2 12398.0 15116.8 15248.5 15270.6 15276.4 4633.0 4633.3 5263.3 5612.0 5670.9 12684.4 12684.4 12686.7 12694.8 12698.4 5597.4 6087.7 6374.1 6374.7 6375.7 5540.6 5640.3 5675.6 5698.3 5907.9 3355.9 4213.8 4351.3 4373.5 4424.2 1245.9 1289.3 1289.6 1292.8 1293.5 617.8 686.8 743.6 757.6 770.7 6240.3 6325.9 6506.5 6615.1 6716.5 879.3 881.1 909.7 910.2 917.7 3569.4 3847.0 3954.7 4027.0 4158.2 13232.9 13854.2 13876.7 13885.9 13903.0 13743.1 13745.3 13757.1 13759.7 13765.3 9828.3 10072.3 10253.9 10267.9 10277.1 6513.9 6528.3 6536.9 6754.5 6798.2 12052.3 12472.1 12538.2 12611.4 12653.4 12204.8 12592.4 12598.7 12606.1 12624.1 8858.3 9006.2 9067.7 9078.7 9085.6 9785.6 9990.8 10136.6 10165.5 10270.0 6633.5 7905.9 7980.5 8001.9 8063.6 8361.2 8389.4 8390.9 8422.5 8492.8 7761.0 7896.6 7913.0 7914.0 7915.2 5750.9 5791.1 5936.9 6017.2 6019.7 5358.4 5630.2 5701.6 5834.3 5968.8 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#4] prototype.html Cycles:180 Average:5109.70 Median:5830.47 stddev:1615.09 (27.7%) stddev-sans-first:1580.25 09:51:08 INFO - PID 1631 | Values: 387.4 394.0 397.4 400.1 401.2 5883.4 5921.6 5955.4 5962.3 6069.6 5186.3 5763.2 5767.3 5851.6 5928.8 3878.9 4375.5 4393.9 4519.5 4532.8 3923.4 4456.4 4478.3 4528.8 4530.6 3533.0 3873.6 4488.5 4497.7 4501.9 3944.8 4041.6 4497.9 4506.0 4547.2 3832.3 3836.7 3852.8 3853.9 3880.1 5322.9 5339.3 5746.5 5778.6 5809.4 5018.9 5167.6 5167.6 5199.5 5213.5 6267.5 6287.2 6327.5 6334.4 6346.4 3561.8 3599.4 3613.8 4307.9 4318.3 6095.0 6438.2 6559.3 6686.5 6748.2 3640.5 3815.6 4373.8 4374.5 4381.0 3799.9 4425.1 4431.8 4449.6 4453.3 5015.0 5131.2 5151.2 5168.1 5211.5 5219.7 6350.0 6363.9 6366.6 6387.6 1494.9 1515.8 1516.2 1519.6 1523.4 855.4 952.7 991.2 996.3 1003.9 6038.8 6040.6 6044.8 6048.9 6049.8 5703.5 6548.5 6577.3 6590.0 6593.8 5159.6 5795.6 5972.5 6143.9 6150.3 2766.7 3129.7 3329.0 3435.8 3446.4 6248.0 6629.1 6777.0 6783.9 6860.0 6388.6 6491.6 6501.9 6662.7 6768.8 5915.3 6231.0 6244.6 6463.7 6700.6 5505.2 6321.6 6612.3 6640.3 6644.5 6179.7 6504.3 6506.7 6513.8 6528.0 5712.0 6818.4 6853.5 6854.5 6878.4 3850.7 4286.4 4327.5 4333.5 4353.9 5937.3 6494.7 6593.4 6606.9 6620.7 6080.3 6332.5 6349.8 6418.5 6445.4 5868.9 6317.7 6328.1 6356.3 6359.5 5216.7 5426.4 5546.8 5997.8 6241.3 4851.1 6246.8 6247.6 6289.7 6339.1 5164.8 5347.1 6136.4 6185.2 6231.6 09:51:08 INFO - PID 1631 | 09:51:08 INFO - PID 1631 | [#5] yui.html Cycles:180 Average:19435.50 Median:16499.25 stddev:27916.81 (169.2%) stddev-sans-first:27959.73 09:51:08 INFO - PID 1631 | Values: 713.2 719.9 731.6 734.7 738.1 15603.8 16485.7 16488.0 16505.7 16540.5 15161.6 15267.8 15290.8 15325.8 16492.8 967.9 992.4 1006.6 1008.0 1010.9 1011.4 1013.6 1013.9 1014.9 1015.8 1016.4 1063.6 1064.4 1066.4 1066.9 1054.1 1059.9 1060.3 1062.9 1063.1 778.1 781.9 784.5 785.0 787.2 16200.3 16223.8 16364.3 16405.6 17051.4 14656.6 14684.2 14711.8 14827.5 14832.9 10826.8 10864.2 11085.9 11154.1 11317.2 3030.0 3086.7 3140.8 3156.8 3201.6 95758.0 108238.0 109592.0 111795.0 112039.0 9369.5 10874.5 11584.3 11603.2 11906.3 20735.5 23057.4 25158.2 25165.0 25173.2 7992.0 8008.7 8013.0 8097.2 8097.9 3502.9 3994.7 4223.0 4246.6 4278.9 3928.3 4081.3 4184.3 4195.2 4667.2 850.1 871.8 897.1 897.5 899.3 478.4 544.2 559.0 561.9 576.3 9966.4 10222.6 10269.3 10322.2 10418.0 568.7 576.2 591.6 595.8 599.7 4448.6 4628.6 4633.7 4668.8 4684.3 95812.5 106429.0 107166.0 107759.5 108708.5 56816.0 85923.0 88277.7 89175.1 90332.0 49219.8 60328.4 77227.2 77273.0 77836.0 10647.9 10672.3 10729.6 10928.8 10935.5 36356.8 36995.4 37627.6 37958.8 38253.9 47657.8 48859.0 49121.3 49600.0 49988.8 12758.5 12985.0 13076.2 13090.8 13738.8 14083.4 14306.2 14408.4 14466.6 14570.3 13104.5 13302.9 13410.4 13723.4 14021.3 13342.6 13387.1 13389.3 13559.7 13654.0 13039.7 13078.7 13093.3 13240.7 13979.2 9520.8 9720.2 9885.4 9996.0 10808.4 9935.7 10182.5 10332.9 11123.8 11392.8 09:51:08 INFO - PID 1631 | -------- Summary: end -------- 09:51:08 INFO - PID 1631 | 09:51:09 INFO - TEST-INFO | 1631: exit 0 09:51:09 INFO - TEST-OK | dromaeo_css | took 1119678ms 09:51:09 INFO - TEST-START | kraken 09:51:09 INFO - operating with platform_type : mac_ 09:51:09 INFO - Initialising browser for kraken test... 09:51:09 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpfcLbWt/profile http://localhost:49240/getInfo.html) 09:51:11 INFO - PID 1640 | __metrics Screen width/height:1600/1200 09:51:11 INFO - PID 1640 | colorDepth:24 09:51:11 INFO - PID 1640 | Browser inner width/height: 1024/689 09:51:11 INFO - PID 1640 | __metrics 09:51:11 ERROR - PID 1640 | 1490892671706 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:51:11 INFO - PID 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:51:11 INFO - PID 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:12 INFO - TEST-INFO | 1640: exit 0 09:51:12 INFO - Browser initialized. 09:51:12 INFO - Running cycle 1/1 for kraken test... 09:51:12 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpfcLbWt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:51:24 INFO - PID 1641 | RSS: Main: 234156032 09:51:24 INFO - PID 1641 | 09:51:27 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:51:28 INFO - PID 1641 | RSS: Main: 286785536 09:51:28 INFO - PID 1641 | 09:51:31 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:51:32 INFO - PID 1641 | RSS: Main: 288444416 09:51:32 INFO - PID 1641 | 09:51:35 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:51:36 INFO - PID 1641 | RSS: Main: 382902272 09:51:36 INFO - PID 1641 | 09:51:38 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:51:39 INFO - PID 1641 | RSS: Main: 286830592 09:51:39 INFO - PID 1641 | 09:51:41 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:42 INFO - PID 1641 | RSS: Main: 289308672 09:51:42 INFO - PID 1641 | 09:51:45 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:45 INFO - PID 1641 | RSS: Main: 329965568 09:51:45 INFO - PID 1641 | 09:51:49 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:49 INFO - PID 1641 | RSS: Main: 338423808 09:51:49 INFO - PID 1641 | 09:51:53 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:51:53 INFO - PID 1641 | RSS: Main: 344334336 09:51:53 INFO - PID 1641 | 09:51:55 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:55 INFO - PID 1641 | RSS: Main: 303722496 09:51:55 INFO - PID 1641 | 09:51:57 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:58 INFO - PID 1641 | RSS: Main: 306544640 09:51:58 INFO - PID 1641 | 09:52:00 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:52:01 INFO - PID 1641 | RSS: Main: 309800960 09:52:01 INFO - PID 1641 | 09:52:04 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:52:05 INFO - PID 1641 | RSS: Main: 326369280 09:52:05 INFO - PID 1641 | 09:52:08 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:52:09 INFO - PID 1641 | RSS: Main: 320798720 09:52:09 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:52:11 INFO - PID 1641 | RSS: Main: 383406080 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | __start_tp_report 09:52:11 INFO - PID 1641 | _x_x_mozilla_page_load 09:52:11 INFO - PID 1641 | _x_x_mozilla_page_load_details 09:52:11 INFO - PID 1641 | |i|pagename|runs| 09:52:11 INFO - PID 1641 | |0;ai-astar;109;121;120;120;121;122;122;124;123;122 09:52:11 INFO - PID 1641 | |1;audio-beat-detection;202;157;149;162;121;120;121;118;145;118 09:52:11 INFO - PID 1641 | |2;audio-dft;227;309;209;216;212;211;207;214;234;273 09:52:11 INFO - PID 1641 | |3;audio-fft;84;65;64;96;66;66;63;65;79;62 09:52:11 INFO - PID 1641 | |4;audio-oscillator;90;81;130;58;83;102;128;111;72;90 09:52:11 INFO - PID 1641 | |5;imaging-gaussian-blur;93;92;95;92;92;91;92;92;94;92 09:52:11 INFO - PID 1641 | |6;imaging-darkroom;101;100;100;101;100;101;101;100;100;128 09:52:11 INFO - PID 1641 | |7;imaging-desaturate;102;100;99;101;102;100;100;123;160;132 09:52:11 INFO - PID 1641 | |8;json-parse-financial;63;63;63;64;65;64;64;64;65;65 09:52:11 INFO - PID 1641 | |9;json-stringify-tinderbox;60;67;77;70;67;65;65;65;65;65 09:52:11 INFO - PID 1641 | |10;stanford-crypto-aes;89;90;89;89;92;88;93;114;116;89 09:52:11 INFO - PID 1641 | |11;stanford-crypto-ccm;164;164;164;166;158;168;149;151;189;171 09:52:11 INFO - PID 1641 | |12;stanford-crypto-pbkdf2;200;204;209;199;199;199;204;199;200;195 09:52:11 INFO - PID 1641 | |13;stanford-crypto-sha256-iterative;73;74;72;72;71;71;75;73;72;73 09:52:11 INFO - PID 1641 | __end_tp_report 09:52:11 INFO - PID 1641 | __start_cc_report 09:52:11 INFO - PID 1641 | _x_x_mozilla_cycle_collect,2228 09:52:11 INFO - PID 1641 | __end_cc_report 09:52:11 INFO - PID 1641 | __startTimestamp1490892731544__endTimestamp 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | ------- Summary: start ------- 09:52:11 INFO - PID 1641 | Number of tests: 14 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#0] ai-astar Cycles:10 Average:120.40 Median:122.00 stddev:4.20 (3.4%) stddev-sans-first:1.32 09:52:11 INFO - PID 1641 | Values: 109.0 121.0 120.0 120.0 121.0 122.0 122.0 124.0 123.0 122.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:147.00 stddev:27.48 (18.7%) stddev-sans-first:18.38 09:52:11 INFO - PID 1641 | Values: 202.0 157.0 149.0 162.0 121.0 120.0 121.0 118.0 145.0 118.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#2] audio-dft Cycles:10 Average:231.20 Median:221.50 stddev:33.68 (15.2%) stddev-sans-first:35.69 09:52:11 INFO - PID 1641 | Values: 227.0 309.0 209.0 216.0 212.0 211.0 207.0 214.0 234.0 273.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#3] audio-fft Cycles:10 Average:71.00 Median:66.00 stddev:11.42 (17.3%) stddev-sans-first:11.10 09:52:11 INFO - PID 1641 | Values: 84.0 65.0 64.0 96.0 66.0 66.0 63.0 65.0 79.0 62.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:76.50 stddev:23.34 (30.5%) stddev-sans-first:24.70 09:52:11 INFO - PID 1641 | Values: 90.0 81.0 130.0 58.0 83.0 102.0 128.0 111.0 72.0 90.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:92.00 stddev:1.18 (1.3%) stddev-sans-first:1.24 09:52:11 INFO - PID 1641 | Values: 93.0 92.0 95.0 92.0 92.0 91.0 92.0 92.0 94.0 92.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:101.00 stddev:8.73 (8.6%) stddev-sans-first:9.22 09:52:11 INFO - PID 1641 | Values: 101.0 100.0 100.0 101.0 100.0 101.0 101.0 100.0 100.0 128.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#7] imaging-desaturate Cycles:10 Average:111.90 Median:112.50 stddev:20.40 (18.1%) stddev-sans-first:21.32 09:52:11 INFO - PID 1641 | Values: 102.0 100.0 99.0 101.0 102.0 100.0 100.0 123.0 160.0 132.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 09:52:11 INFO - PID 1641 | Values: 63.0 63.0 63.0 64.0 65.0 64.0 64.0 64.0 65.0 65.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.00 stddev:4.43 (6.7%) stddev-sans-first:4.00 09:52:11 INFO - PID 1641 | Values: 60.0 67.0 77.0 70.0 67.0 65.0 65.0 65.0 65.0 65.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:89.00 stddev:10.71 (12.0%) stddev-sans-first:11.15 09:52:11 INFO - PID 1641 | Values: 89.0 90.0 89.0 89.0 92.0 88.0 93.0 114.0 116.0 89.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#11] stanford-crypto-ccm Cycles:10 Average:164.40 Median:165.00 stddev:11.17 (6.8%) stddev-sans-first:11.84 09:52:11 INFO - PID 1641 | Values: 164.0 164.0 164.0 166.0 158.0 168.0 149.0 151.0 189.0 171.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:200.00 stddev:3.88 (1.9%) stddev-sans-first:4.11 09:52:11 INFO - PID 1641 | Values: 200.0 204.0 209.0 199.0 199.0 199.0 204.0 199.0 200.0 195.0 09:52:11 INFO - PID 1641 | 09:52:11 INFO - PID 1641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:73.00 stddev:1.26 (1.7%) stddev-sans-first:1.33 09:52:11 INFO - PID 1641 | Values: 73.0 74.0 72.0 72.0 71.0 71.0 75.0 73.0 72.0 73.0 09:52:11 INFO - PID 1641 | -------- Summary: end -------- 09:52:11 INFO - PID 1641 | 09:52:12 INFO - TEST-INFO | 1641: exit 0 09:52:12 INFO - TEST-OK | kraken | took 63211ms 09:52:12 INFO - SUITE-END | took 1182s 09:52:12 INFO - Completed test suite (00:19:43) 09:52:12 INFO - javascript benchmark 09:52:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7568.006728294663, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.7379672727816, 3432.639572844851, 3484.756067075303, 3502.492138639897, 3545.078279647291, 6070.484008859252, 6110.892920173598, 6221.346758590345, 6231.501479881605, 6235.625862448251, 6151.108089327045, 6160.84597885052, 6227.719752611132, 6239.532035097374, 6259.280182778982, 5341.145416733323, 5481.616286859921, 5538.086215774402, 5556.722163891809, 5682.687452190128, 5575.275214222166, 5642.322921249435, 5642.548596112301, 5653.208550802891, 5658.575606829484, 5402.108652072403, 5481.985832620938, 5499.257600223958, 5508.228847961289, 5571.888562228753, 5595.048841696921, 5602.13166959122, 5603.7478313475995, 5612.663240205601, 5644.576656750719, 5028.396592408898, 5501.422350653163, 5512.200730894038, 5531.3362396512675, 5533.474319939599, 5571.637843540157, 6176.752929882799, 6186.9381306186815, 6190.257169139687, 6204.5967012144065, 6330.303666596671, 6381.8085457436355, 6385.329540398284, 6426.839329016812, 6535.836604084889, 6529.34706529348, 6623.105880706091, 6662.167229096363, 6667.433268172211, 6680.231773346079, 6242.001279795211, 6307.148534947769, 6322.873542529123, 6329.746810127589, 6338.0, 6520.543561950618, 6609.239937407164, 6632.767853125118, 6822.897656535156, 6831.180258369028, 6384.170057892537, 6475.546711730133, 6481.902771458432, 6483.09236706852, 6519.608823470607, 6551.639659818756, 6595.901061484082, 6615.470762339001, 6616.735330586819, 6625.469962402996, 6337.619742815354, 6413.230412350455, 6517.055027021055, 6519.3154718754795, 6537.542372033912, 6371.203599550056, 6374.426301632875, 6376.266729326094, 6392.4886009119155, 6398.680065996681, 5156.123459011977, 5291.550218231417, 5335.039692855245, 5338.092524270884, 5356.223347614574, 4673.649476289292, 4676.883077923079, 4684.484706682262, 4685.554872287127, 4689.929651055237, 6329.082283069021, 6329.3354197809485, 6336.04959256106, 6343.746250150035, 6358.586691865106, 6400.679966001681, 6470.9029364559565, 6546.607203567706, 6628.834279142963, 6652.101966234593, 6336.20797400325, 6352.523560732871, 6364.490840732823, 6369.9681501592195, 6378.394052564999, 5765.567582431335, 5800.6809625471005, 5806.390329015477, 5816.767329306865, 5850.034744267148, 6562.704678289584, 6641.136652235178, 6649.434798042221, 6796.422304104207, 6853.9314606853295, 6557.409833114852, 6617.305182955721, 6631.038499417654, 6631.469482441489, 6651.0023496475915, 6779.525433219627, 6805.625690587065, 6816.216135144522, 6816.318368163343, 6830.316968303328, 5305.389880163831, 6448.484121270381, 6498.0, 6504.38208370204, 6505.0, 5569.0, 6450.032495125769, 6462.289148193789, 6531.281559028408, 6597.307282735435, 6421.165248517663, 6432.485401167802, 6438.774642887476, 6460.967695161494, 6467.870642587028, 6343.936560634519, 6401.263854656588, 6413.326600706859, 6422.036694495863, 6429.2284925807335, 6136.059583474799, 6237.5321850860455, 6237.937620623918, 6294.370562943852, 6367.936320636734, 5420.647657902224, 6422.486201103807, 6424.743010279536, 6430.549861509649, 6435.613863168225, 6328.177336946352, 6354.90467642995, 6356.173697419306, 6357.0, 6395.488360931207], "value": 6042.453175360022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.6355531568267, 801.1787917384681, 814.6944895664124, 1113.7661091170853, 1137.8691450483198, 28121.718782812164, 37951.62048379515, 38376.23247535047, 38389.808050959706, 38394.84815455534, 39807.0, 40228.59771402285, 40349.7982510087, 40358.991025224386, 40376.0, 6056.697165141747, 8405.159484051606, 8538.658453661847, 8544.487330760143, 8561.614727337253, 5317.2821669074565, 6250.0624906263965, 6252.531060170482, 6264.154339164223, 6274.498040156776, 8386.245237928586, 8539.871901921477, 8557.400981931267, 8570.142985701412, 8573.828523429527, 8499.830003399928, 8556.657733690645, 8561.529115898624, 8583.699570515002, 8601.354898382584, 8310.044344900292, 8393.53835539045, 8398.03422606402, 8404.873926891101, 8412.495250285003, 37539.4369084464, 37711.43432848509, 37772.24455510902, 37788.62211377879, 37902.43146352807, 2993.4162838246552, 3002.3463631371287, 3013.1563162314587, 3026.228311780482, 3032.6057612510454, 20774.58450830975, 27385.31536711578, 27555.86222068897, 27787.58318625202, 28203.43593128126, 2766.5573508238786, 2803.8457884816326, 2804.9439011219865, 2808.69104398516, 2892.3492214251837, 312956.0, 313108.0, 313889.0, 313938.0, 315203.0, 49064.528064158134, 49163.50836491661, 49209.26186107211, 49247.0, 49338.75330623324, 52552.73723631357, 52589.47410525846, 52684.47315526796, 52758.736206318725, 52806.871072405505, 14447.566573002616, 14575.562733118024, 14582.708345833023, 14590.927045364706, 14606.34271457787, 12028.458719357475, 12037.759244815055, 12039.458224379923, 12059.57791477294, 12066.939665301617, 10178.338408003456, 10351.482425878676, 10366.61567839386, 11298.943505282421, 12305.56930507428, 5262.578993680573, 5433.375161856358, 5434.67391956476, 5436.809711660073, 5437.782488700487, 956.2588993529931, 970.6748239339901, 973.454865275448, 987.1559816357055, 1001.3140998416109, 24183.879080604485, 24216.87891560531, 24237.151699690425, 24260.636090458658, 24296.51406971886, 1074.1245884604023, 1083.5340803454433, 1110.7389763405592, 1115.55935405516, 1119.9664010079712, 9528.618855245713, 12267.877321226852, 12306.323152226714, 12340.259584424963, 12357.876421235673, 425110.0, 425910.9271258292, 426121.0, 426144.0, 426177.0, 43172.0, 52618.47381526212, 52794.47205527972, 53045.469545304826, 53070.73464632652, 1372.6568357910523, 1446.8697817196503, 1457.3806132393902, 1462.5904746671163, 1495.3046833222386, 22911.19810806614, 23447.0, 23607.527849443257, 23612.0, 23704.525909482058, 36870.631293687795, 36899.446508302935, 36907.63092369042, 36939.99999999892, 36967.44548831823, 394253.0287348545, 397776.0222397739, 400575.0, 400613.99693002517, 400662.0, 28865.85567072151, 29538.55692164662, 29657.5551366734, 29721.851390742908, 32513.512297316032, 31236.0, 35331.64668353284, 37241.0, 43665.34501982536, 44342.77828610836, 28598.285042873264, 29160.730442954868, 29389.0, 30388.936387226342, 30726.0, 23597.410064748517, 27098.547899327055, 29885.70114298829, 30076.5488517668, 30241.395172095996, 31722.68277317197, 35424.291514169054, 36664.0, 36716.89849304525, 36806.81596592107, 29314.56028159622, 29725.0, 29732.702672973857, 30602.540961885144, 30809.0, 22671.919948725612, 23739.881300593388, 24177.615133880918, 24328.27015189547, 24339.878300608383], "value": 17069.96765567877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.657164411005, 425.25155725922366, 427.7754179055994, 430.2470676316446, 444.63984172820017, 19715.01424928755, 21529.784702152974, 22235.22176723815, 22274.88862555687, 22275.44311392216, 21279.787202127973, 22312.979579594623, 22797.886010569964, 22966.655500167468, 23255.0, 6158.445739883421, 6351.237851457809, 6355.555111142222, 6370.649614271213, 6371.553991220617, 3732.328180927432, 5424.457554244584, 6283.654399008075, 6344.33384494626, 6348.9682551587175, 5989.251343582052, 6307.211598550181, 6311.779087731908, 6318.684065796715, 6344.080108384282, 6111.113888486167, 6224.564280500367, 6243.313235544109, 6246.906296405512, 6248.968920128196, 5328.520433160996, 5481.698506582176, 5529.806456774034, 5540.36285827131, 5563.499285064364, 26427.0, 26498.675066246804, 26548.73451265482, 26553.73446265532, 27026.864865675547, 2045.1819272291054, 2052.127845665586, 2057.4753437873246, 2063.5253891604866, 2079.334612923865, 15508.379664813394, 15643.608909777233, 15989.440369587124, 16310.918445407697, 16483.58791030222, 1590.624110144728, 1598.8081430228447, 1607.5097095385902, 1646.7941507311587, 1651.4797838680777, 132889.0, 139318.30340848232, 139526.0, 140457.29771351078, 141968.12446257533, 11592.6522204334, 11848.526058957545, 11926.403679816149, 11945.462454189583, 12053.397330133634, 15835.762463562825, 15835.841641583667, 15841.603959901095, 15857.762133568005, 15864.682706345808, 8555.871661925075, 8726.69456569017, 8739.912600874037, 8779.956100219459, 8914.86627700585, 6865.656717164122, 8421.19998600132, 8585.527795971157, 8588.699395521127, 8612.65549378018, 6136.386361363828, 7280.216346994915, 7448.73929412468, 7460.3658689012045, 7484.139323977813, 2090.278853795438, 2104.3266154830308, 2104.863183893042, 2107.9578408432053, 2113.1230539326316, 937.8499440089553, 947.1049857884371, 948.8102379524123, 962.6823148361024, 966.3622009473713, 18953.620927581647, 20562.074706638236, 20621.89689051545, 20622.0, 20991.895040524698, 857.8498762716499, 893.5085702863399, 941.275218082073, 983.8524221366853, 1015.1574193419444, 8759.0, 9535.994882149515, 10330.741731456774, 10787.352758834495, 10814.89185108139, 619323.0, 635270.0, 635439.0, 642091.0, 648270.0, 159164.0, 160287.0, 162112.0, 162416.0, 165828.0, 1159.2979425160972, 1186.2348785033605, 1217.0567809947186, 1225.185251807556, 1225.2587184753097, 17707.557311067325, 17824.643507129524, 17945.282188712827, 18219.45341639753, 18222.817771822112, 47553.81115472141, 61370.69314653398, 61593.15220543391, 62845.05732413926, 63222.68388658027, 85749.71375429498, 85980.57009714912, 87996.68004980059, 88259.0, 88324.55837720771, 7490.325870671666, 8111.918880811117, 8560.614772335135, 8696.739097827076, 8902.154295341932, 13526.729465410834, 13630.04589678713, 13641.454341826216, 14056.578302650936, 14080.788788168391, 11719.296842189497, 11895.10786691019, 11905.880941190477, 12064.517419303129, 12113.365990202728, 10862.076723478593, 11000.339979601249, 11088.778224435628, 11338.659840204808, 11425.771484570096, 11581.652550423501, 12031.578894738643, 12068.215565988183, 12095.153339266168, 12106.75786484283, 8477.152284771473, 8503.34389999083, 8737.126287371466, 8855.60149793248, 8874.423162494417, 11538.307701537935, 11729.589464368706, 12042.819357709466, 12111.0, 12132.696682583006], "value": 10264.77895396159, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.2870277833297, 1273.4587800184922, 1407.46516323797, 1430.8211473565805, 1442.1131004432275, 9237.49193794341, 9677.61289548418, 10690.946545267283, 10980.945095274512, 11029.172812039089, 10639.78720425591, 10763.62327318544, 10767.83848242277, 10768.892311076886, 10771.730706732336, 2402.3994001499627, 2452.6443665668467, 2453.0, 2456.45957889265, 2459.5203935232635, 2090.0385822521584, 2172.31572054803, 2173.9021744021557, 2175.8476906616543, 2177.8693278403266, 2113.2814842953394, 2289.8168146548237, 2362.9291121266388, 2377.4888398994212, 2398.388410955204, 2386.4153282445736, 2393.222202784102, 2393.4495066134887, 2393.904243830245, 2394.5570069537193, 2365.7634236576205, 2403.2189538399916, 2404.206611818096, 2406.31420045288, 2406.9157579484636, 10654.360738355646, 10775.191860610406, 10786.406747628876, 10804.513796879159, 10851.348919064882, 6105.969470152665, 6338.683065846689, 6344.016677415008, 6345.8413539661415, 6353.777617783356, 3667.889963301105, 3842.53889533255, 4007.6593489553134, 4019.356902895552, 4023.97988010061, 1125.819868820993, 1160.628598848369, 1185.2473679213674, 1211.9757604847853, 1215.2465471407784, 12398.0, 15116.773248401283, 15248.466303679317, 15270.618234544225, 15276.38894444221, 4632.953670463319, 4633.328167415706, 5263.315768950089, 5612.017896868113, 5670.865826834567, 12684.365781711062, 12684.365781711062, 12686.682832929066, 12694.80957785634, 12698.42857071434, 5597.412271711493, 6087.726052327655, 6374.075759014917, 6374.681265936684, 6375.681215939185, 5540.556755459554, 5640.266765320482, 5675.631083979528, 5698.287714035746, 5907.911381329283, 3355.8925554566936, 4213.768242746678, 4351.282038463618, 4373.518912919576, 4424.159409712144, 1245.8940990015951, 1289.265118882247, 1289.5615490733196, 1292.844858616972, 1293.495536740672, 617.7528988404674, 686.7699320727512, 743.624469642827, 757.5946868425476, 770.6570576093696, 6240.31356550779, 6325.936740632535, 6506.479481641457, 6615.073889655452, 6716.46268298535, 879.3317079019863, 881.131915614683, 909.6725178935578, 910.1808372464702, 917.6925729880564, 3569.446735755962, 3846.9807650962125, 3954.683625310026, 4026.9597304026584, 4158.2307273154565, 13232.867671323356, 13854.16874987504, 13876.653083672787, 13885.889128869667, 13903.026788124937, 13743.106698064594, 13745.312734363442, 13757.10578812374, 13759.656008599864, 13765.311734413239, 9828.31201815866, 10072.294939354322, 10253.897461025294, 10267.897321026694, 10277.126444252322, 6513.869722605426, 6528.347165283433, 6536.869262614816, 6754.527183097136, 6798.184217893884, 12052.337121458053, 12472.126951113334, 12538.247705137721, 12611.369431528203, 12653.367331633197, 12204.816927745913, 12592.370381481072, 12598.748025039264, 12606.117571769888, 12624.053196010153, 8858.29133669292, 9006.24378246262, 9067.68263110788, 9078.727638170865, 9085.636574536944, 9785.608575656894, 9990.750231243986, 10136.594536218468, 10165.54255058539, 10270.024347686958, 6633.502487313374, 7905.920940790748, 7980.4812687175145, 8001.919980800118, 8063.596820159086, 8361.247487726134, 8389.370797190113, 8390.874136887829, 8422.452540584842, 8492.830143396974, 7761.0, 7896.565688887166, 7913.0, 7913.9604301978125, 7915.168907264654, 5750.856228594152, 5791.102379131242, 5936.910946335895, 6017.187679663364, 6019.6990150493175, 5358.383785864676, 5630.183623374506, 5701.600887937971, 5834.27071616048, 5968.76124954997], "value": 5100.151383494421, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4304771985182, 393.9842406303748, 397.38008706417975, 400.05187705138826, 401.24966312994684, 5883.352831188566, 5921.5558833087525, 5955.404459554043, 5962.314333851608, 6069.57512974092, 5186.299849520314, 5763.221965034719, 5767.269819054278, 5851.552819856907, 5928.762849486026, 3878.9030274243228, 4375.453068366454, 4393.945453091267, 4519.457665080186, 4532.796024178903, 3923.431102490137, 4456.353828694837, 4478.328250762395, 4528.796204170803, 4530.637548996089, 3533.0, 3873.573906870242, 4488.461384633849, 4497.662675299349, 4501.942043619747, 3944.757401418544, 4041.6362527372385, 4497.910041799162, 4506.008678090815, 4547.226971414867, 3832.3485007548784, 3836.731428799957, 3852.84588616455, 3853.9421908671393, 3880.1463677990796, 5322.855586049018, 5339.252504649352, 5746.54027677789, 5778.566607504454, 5809.390014048507, 5018.899622007576, 5167.612429067835, 5167.612429067835, 5199.454057323965, 5213.4525874782985, 6267.5299352548745, 6287.245530536365, 6327.4938004959495, 6334.448060225228, 6346.397976829524, 3561.821908904544, 3599.3701102307255, 3613.7831730096277, 4307.892302692427, 4318.287482565373, 6094.96952515239, 6438.227412710505, 6559.34406559345, 6686.531942764009, 6748.190217173921, 3640.5449318835144, 3815.5802861685193, 4373.759443230653, 4374.496932852699, 4381.014271788886, 3799.8790356844797, 4425.114977004612, 4431.844885429059, 4449.57729015743, 4453.3320001999955, 5014.974925125351, 5131.179011358165, 5151.201563757623, 5168.147255702767, 5211.478852114834, 5219.739013049332, 6350.0, 6363.9045414318825, 6366.633366633367, 6387.636574467384, 1494.880409567232, 1515.8256800468087, 1516.196415899575, 1519.56692342684, 1523.4210999819998, 855.4054931822324, 952.7379970508166, 991.2020823237364, 996.3175224970918, 1003.8694969653898, 6038.849028774228, 6040.637561746309, 6044.821259854286, 6048.90926636092, 6049.818505444844, 5703.54371650267, 6548.508861835381, 6577.309382514863, 6590.0, 6593.80218593443, 5159.561437277874, 5795.565332600072, 5972.492338151305, 6143.877122457526, 6150.292716337589, 2766.681831589353, 3129.6713845046397, 3329.0, 3435.811030393302, 3446.3802013053823, 6248.0, 6629.149465729783, 6777.000784768505, 6783.879415468939, 6860.010596926835, 6388.552801303864, 6491.610503369813, 6501.934980650133, 6662.7334906604165, 6768.796936091925, 5915.349311575637, 6231.040255878422, 6244.59583060525, 6463.709133089116, 6700.631465269457, 5505.201745746925, 6321.557490975588, 6612.272650008399, 6640.302768209269, 6644.46844252449, 6179.660118693515, 6504.349565043647, 6506.739730410731, 6513.804585862432, 6527.967360163168, 5711.9718450679275, 6818.386345228953, 6853.520253582202, 6854.485913556404, 6878.38094571491, 3850.6726928211415, 4286.357046442934, 4327.545607711145, 4333.458317710286, 4353.891152721208, 5937.317208521075, 6494.707738151889, 6593.3736295051895, 6606.867862642816, 6620.702068407029, 6080.348533371885, 6332.5303131779565, 6349.777757778455, 6418.518611104092, 6445.4199122079235, 5868.94131058684, 6317.747290108528, 6328.12070507927, 6356.266805273776, 6359.459445946961, 5216.66091704038, 5426.430224826337, 5546.813729163246, 5997.820065398045, 6241.251049874044, 4851.1412995082455, 6246.812595622138, 6247.562670613013, 6289.685515724287, 6339.098270518918, 5164.845054648366, 5347.144456886879, 6136.386361364007, 6185.165002724574, 6231.626102433868], "value": 4608.583302756422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.2119008495615, 719.8704233238016, 731.5644759437381, 734.6767422334173, 738.0958326050588, 15603.84396156038, 16485.67028659426, 16488.0, 16505.669886602274, 16540.503784886456, 15161.620959476018, 15267.770983435257, 15290.770638440432, 15325.846741532581, 16492.83507164928, 967.9419234845914, 992.3946392700446, 1006.6174853555639, 1008.0222184481079, 1010.9342892711987, 1011.3931641015405, 1013.5793645637052, 1013.944233067181, 1014.8934361891969, 1015.8222311095528, 1016.4054028393399, 1063.6064656077262, 1064.3826580583282, 1066.3761699405818, 1066.9413182274973, 1054.140875186724, 1059.8781140168903, 1060.2525219720078, 1062.9255952083358, 1063.1441689439998, 778.0507780507828, 781.929626333627, 784.5371230973695, 784.9843003139962, 787.1734678587518, 16200.270987805578, 16223.756643650355, 16364.34542618294, 16405.58986025347, 17051.403200887908, 14656.560303190921, 14684.192369419678, 14711.779323310158, 14827.54351647814, 14832.851671483255, 10826.783464330749, 10864.185186111074, 11085.944570277177, 11154.051905587954, 11317.207795454322, 3030.0, 3086.737627301682, 3140.764442666809, 3156.8421578921193, 3201.5677883485664, 95758.0424195756, 108238.0, 109592.0, 111795.0, 112039.0, 9369.48467834262, 10874.510647020903, 11584.304941703525, 11603.187776855539, 11906.345151016603, 20735.481612959797, 23057.42356441069, 25158.24525264245, 25165.0, 25173.24480265595, 7992.000999875016, 8008.71969481077, 8012.998375203099, 8097.2307630774985, 8097.878531822027, 3502.9124271893406, 3994.7403418777685, 4222.978885105616, 4246.617804397557, 4278.935815962763, 3928.332183528807, 4081.285774989365, 4184.28867092595, 4195.160967806451, 4667.206574882278, 850.0904032685091, 871.7994861181963, 897.1297841094129, 897.4974014551872, 899.3120262998812, 478.4043865387545, 544.2000259618354, 558.9720513974285, 561.898858205527, 576.331649053778, 9966.40201587907, 10222.6422075227, 10269.281150319406, 10322.174226061898, 10418.037854763768, 568.692905830855, 576.1703147467633, 591.5681552466723, 595.761695321866, 599.6731781179268, 4448.644108471314, 4628.63455280693, 4633.675642705046, 4668.789904454307, 4684.344191813149, 95812.52093739486, 106429.0, 107166.0, 107759.46120269349, 108708.4564577172, 56816.0, 85923.0, 88277.67583486253, 89175.10824891798, 90332.0, 49219.75390123098, 60328.39671603315, 77227.22772772313, 77273.0, 77836.0, 10647.94676026615, 10672.252942294275, 10729.551510545976, 10928.836067458835, 10935.453227338443, 36356.81821590875, 36995.44506832454, 37627.623723762415, 37958.810205948794, 38253.85238442851, 47657.76171119122, 48859.0, 49121.263181051596, 49600.0, 49988.75005624948, 12758.48966041348, 12985.0, 13076.215427074025, 13090.803637945248, 13738.793918091436, 14083.436662533793, 14306.213158275978, 14408.351624176728, 14466.566003019927, 14570.34433450476, 13104.475820967054, 13302.93348533251, 13410.365369539026, 13723.439583261004, 14021.29893505341, 13342.599722007966, 13387.078290591415, 13389.263590502616, 13559.661008474866, 13654.0, 13039.739205216032, 13078.738425231251, 13093.25041741245, 13240.668983275495, 13979.161250325013, 9520.823379518899, 9720.222382209266, 9885.403287471072, 9996.0, 10808.351498910091, 9935.701928942144, 10182.49087545605, 10332.948335258276, 11123.777524449304, 11392.772144557228], "value": 7549.370414916646, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 120.0, 120.0, 121.0, 122.0, 122.0, 124.0, 123.0, 122.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 157.0, 149.0, 162.0, 121.0, 120.0, 121.0, 118.0, 145.0, 118.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 309.0, 209.0, 216.0, 212.0, 211.0, 207.0, 214.0, 234.0, 273.0], "value": 231.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 65.0, 64.0, 96.0, 66.0, 66.0, 63.0, 65.0, 79.0, 62.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 81.0, 130.0, 58.0, 83.0, 102.0, 128.0, 111.0, 72.0, 90.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 95.0, 92.0, 92.0, 91.0, 92.0, 92.0, 94.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 100.0, 100.0, 101.0, 100.0, 101.0, 101.0, 100.0, 100.0, 128.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 100.0, 99.0, 101.0, 102.0, 100.0, 100.0, 123.0, 160.0, 132.0], "value": 111.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 65.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 77.0, 70.0, 67.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 92.0, 88.0, 93.0, 114.0, 116.0, 89.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 166.0, 158.0, 168.0, 149.0, 151.0, 189.0, 171.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 204.0, 209.0, 199.0, 199.0, 199.0, 204.0, 199.0, 200.0, 195.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 72.0, 71.0, 71.0, 75.0, 73.0, 72.0, 73.0], "value": 72.6, "unit": "score"}]}]} 09:52:12 INFO - Return code: 0 09:52:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:52:12 ERROR - # TBPL SUCCESS # 09:52:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:12 INFO - 2017-03-30 09:52:12.854 screenresolution[1645:9736] starting screenresolution argv=screenresolution get 09:52:12 INFO - 2017-03-30 09:52:12.856 screenresolution[1645:9736] Display 0: 1600x1200x32@60 09:52:12 INFO - 2017-03-30 09:52:12.863 screenresolution[1646:9739] starting screenresolution argv=screenresolution list 09:52:12 INFO - Available Modes on Display 0 09:52:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:13 INFO - Intel Iris: 09:52:13 INFO - Chipset Model: Intel Iris 09:52:13 INFO - Type: GPU 09:52:13 INFO - Bus: Built-In 09:52:13 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:13 INFO - Vendor: Intel (0x8086) 09:52:13 INFO - Device ID: 0x0a2e 09:52:13 INFO - Revision ID: 0x0009 09:52:13 INFO - Displays: 09:52:13 INFO - Display: 09:52:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:13 INFO - Display Serial Number: 1600x1200 60 09:52:13 INFO - Main Display: Yes 09:52:13 INFO - Mirror: Off 09:52:13 INFO - Online: Yes 09:52:13 INFO - Rotation: Supported 09:52:13 INFO - Return code: 0 09:52:13 INFO - Running post-action listener: _resource_record_post_action 09:52:13 INFO - [mozharness: 2017-03-30 16:52:13.097738Z] Finished run-tests step (success) 09:52:13 INFO - Running post-run listener: _resource_record_post_run 09:52:13 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 38130688; Write bytes: 514076672; Read time: 353; Write time: 1560 09:52:13 INFO - TinderboxPrint: CPU usage
53.3% 09:52:13 INFO - TinderboxPrint: I/O read bytes / time
38,130,688 / 353 09:52:13 INFO - TinderboxPrint: I/O write bytes / time
514,076,672 / 1,560 09:52:13 INFO - TinderboxPrint: CPU idle
2,248.1 (46.7%) 09:52:13 INFO - TinderboxPrint: CPU system
957.9 (19.9%) 09:52:13 INFO - TinderboxPrint: CPU user
1,611.7 (33.5%) 09:52:13 INFO - TinderboxPrint: Swap in / out
249,585,664 / 0 09:52:13 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205085184; Write bytes: 205318144; Read time: 15611; Write time: 427 09:52:13 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 36312064; Write bytes: 297928704; Read time: 336; Write time: 1098 09:52:13 INFO - Running post-run listener: _upload_blobber_files 09:52:13 INFO - Blob upload gear active. 09:52:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:52:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:52:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:52:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:52:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:52:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:52:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:14 INFO - (blobuploader) - INFO - Done attempting. 09:52:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:52:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:52:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:15 INFO - (blobuploader) - INFO - Done attempting. 09:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:52:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:16 INFO - (blobuploader) - INFO - Done attempting. 09:52:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:52:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:20 INFO - (blobuploader) - INFO - Done attempting. 09:52:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:52:20 INFO - Return code: 0 09:52:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:52:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:52:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c6c279a0a7b7c48777fa7b3c29f0f4660bd0893ceddb3c80a180803d7dd8134786086da78cffb850b498d9fcad6954b5e283f21ca4ad8ee8e2adf1852102f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f96e40e9c282fbdcd79403ca0569b77fe02cdd1ded6ad6dffce69979415c4a5c2d5fdd9d44ec20ec08d845ac96ceec2b3ec9813ba6a81933439a6594fe3d378f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ff418e136417bc2ad25f4d599017582115391e1f7e175a701b46819728f645c589bc41caf10d24c9dc601813d37a92ab1c551e36793654e33f00d74469cfe95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d68dbb252eba7e759f01d5069ce50e2e93edeaad62a398755b011d35ad5e92634ee61a3498b62b7e30ff7c115ab277dff741dbc6ab7c8620101fb519b7d6cf7"} 09:52:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:52:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:52:20 INFO - Contents: 09:52:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c6c279a0a7b7c48777fa7b3c29f0f4660bd0893ceddb3c80a180803d7dd8134786086da78cffb850b498d9fcad6954b5e283f21ca4ad8ee8e2adf1852102f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f96e40e9c282fbdcd79403ca0569b77fe02cdd1ded6ad6dffce69979415c4a5c2d5fdd9d44ec20ec08d845ac96ceec2b3ec9813ba6a81933439a6594fe3d378f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ff418e136417bc2ad25f4d599017582115391e1f7e175a701b46819728f645c589bc41caf10d24c9dc601813d37a92ab1c551e36793654e33f00d74469cfe95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d68dbb252eba7e759f01d5069ce50e2e93edeaad62a398755b011d35ad5e92634ee61a3498b62b7e30ff7c115ab277dff741dbc6ab7c8620101fb519b7d6cf7"} 09:52:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:52:20 INFO - Copying logs to upload dir... 09:52:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:52:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.933776 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 09:52:20.241360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 09:52:20.244951) ========= 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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/Firefox/sha512/c9c6c279a0a7b7c48777fa7b3c29f0f4660bd0893ceddb3c80a180803d7dd8134786086da78cffb850b498d9fcad6954b5e283f21ca4ad8ee8e2adf1852102f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f96e40e9c282fbdcd79403ca0569b77fe02cdd1ded6ad6dffce69979415c4a5c2d5fdd9d44ec20ec08d845ac96ceec2b3ec9813ba6a81933439a6594fe3d378f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ff418e136417bc2ad25f4d599017582115391e1f7e175a701b46819728f645c589bc41caf10d24c9dc601813d37a92ab1c551e36793654e33f00d74469cfe95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d68dbb252eba7e759f01d5069ce50e2e93edeaad62a398755b011d35ad5e92634ee61a3498b62b7e30ff7c115ab277dff741dbc6ab7c8620101fb519b7d6cf7"} build_url:https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c6c279a0a7b7c48777fa7b3c29f0f4660bd0893ceddb3c80a180803d7dd8134786086da78cffb850b498d9fcad6954b5e283f21ca4ad8ee8e2adf1852102f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f96e40e9c282fbdcd79403ca0569b77fe02cdd1ded6ad6dffce69979415c4a5c2d5fdd9d44ec20ec08d845ac96ceec2b3ec9813ba6a81933439a6594fe3d378f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ff418e136417bc2ad25f4d599017582115391e1f7e175a701b46819728f645c589bc41caf10d24c9dc601813d37a92ab1c551e36793654e33f00d74469cfe95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d68dbb252eba7e759f01d5069ce50e2e93edeaad62a398755b011d35ad5e92634ee61a3498b62b7e30ff7c115ab277dff741dbc6ab7c8620101fb519b7d6cf7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 09:52:20.279596) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:52:20.279917) ========= 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.pwBK0ZTfGP/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.4NNRmraaTr/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.004486 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:52:20.328467) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 09:52:20.328824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 09:52:20.329359) ========= ========= Total master_lag: 0.28 =========