builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0079 starttime: 1493915850.02 results: success (0) buildid: 20170504080520 builduid: 0bfb350148674723b6f03a0744eb8853 revision: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.018770) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.019243) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.019527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.048313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.048972) ========= 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.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-05-04 09:37:30-- 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% 168M=0s 2017-05-04 09:37:30 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179399 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.254502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.254850) ========= 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.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.028570 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.307169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 09:37:30.307537) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a --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 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-05-04 09:37:30,408 truncating revision to first 12 chars 2017-05-04 09:37:30,408 Setting DEBUG logging. 2017-05-04 09:37:30,408 attempt 1/10 2017-05-04 09:37:30,409 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6d4525b5aaa8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 09:37:31,660 unpacking tar archive at: mozilla-central-6d4525b5aaa8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526905 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 09:37:31.860628) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:31.861124) ========= script_repo_revision: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:31.861771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:31.862209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:31.884850) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-04 09:37:31.885487) ========= /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.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:37:32 INFO - MultiFileLogger online at 20170504 09:37:32 in /builds/slave/test 09:37:32 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:37:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:32 INFO - {'append_to_log': False, 09:37:32 INFO - 'base_work_dir': '/builds/slave/test', 09:37:32 INFO - 'blob_upload_branch': 'Firefox', 09:37:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:32 INFO - 'branch': 'Firefox', 09:37:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:32 INFO - 'config_files': ('talos/mac_config.py',), 09:37:32 INFO - 'default_actions': ('clobber', 09:37:32 INFO - 'read-buildbot-config', 09:37:32 INFO - 'download-and-extract', 09:37:32 INFO - 'populate-webroot', 09:37:32 INFO - 'create-virtualenv', 09:37:32 INFO - 'install', 09:37:32 INFO - 'run-tests'), 09:37:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:32 INFO - 'download_minidump_stackwalk': True, 09:37:32 INFO - 'download_symbols': 'ondemand', 09:37:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:32 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:32 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:32 INFO - 'gecko_profile': False, 09:37:32 INFO - 'gecko_profile_interval': 0, 09:37:32 INFO - 'installer_path': 'installer.exe', 09:37:32 INFO - 'log_level': 'info', 09:37:32 INFO - 'log_name': 'talos', 09:37:32 INFO - 'log_to_console': True, 09:37:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:37:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:37:32 INFO - 'opt_config_files': (), 09:37:32 INFO - 'pip_index': False, 09:37:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:32 INFO - 'cmd': ('bash', 09:37:32 INFO - '-c', 09:37:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:32 INFO - 'enabled': True, 09:37:32 INFO - 'halt_on_failure': False, 09:37:32 INFO - 'name': 'check_screen_resolution'},), 09:37:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:32 INFO - 'cmd': ('bash', 09:37:32 INFO - '-c', 09:37:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:32 INFO - 'enabled': True, 09:37:32 INFO - 'halt_on_failure': False, 09:37:32 INFO - 'name': 'check_screen_resolution'},), 09:37:32 INFO - 'run_cmd_checks_enabled': True, 09:37:32 INFO - 'suite': 'dromaeojs', 09:37:32 INFO - 'system_bits': '32', 09:37:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:32 INFO - 'title': 't-yosemite-r7-0079', 09:37:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:32 INFO - 'use_talos_json': True, 09:37:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:32 INFO - 'work_dir': 'build'} 09:37:32 INFO - [mozharness: 2017-05-04 16:37:32.098100Z] Running clobber step. 09:37:32 INFO - Running pre-action listener: _resource_record_pre_action 09:37:32 INFO - Running main action method: clobber 09:37:32 INFO - rmtree: /builds/slave/test/build 09:37:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:34 INFO - Running post-action listener: _resource_record_post_action 09:37:34 INFO - [mozharness: 2017-05-04 16:37:34.574026Z] Finished clobber step (success) 09:37:34 INFO - [mozharness: 2017-05-04 16:37:34.574177Z] Running read-buildbot-config step. 09:37:34 INFO - Running pre-action listener: _resource_record_pre_action 09:37:34 INFO - Running main action method: read_buildbot_config 09:37:34 INFO - Using buildbot properties: 09:37:34 INFO - { 09:37:34 INFO - "project": "", 09:37:34 INFO - "product": "firefox", 09:37:34 INFO - "script_repo_revision": "production", 09:37:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:37:34 INFO - "repository": "", 09:37:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:37:34 INFO - "buildid": "20170504080520", 09:37:34 INFO - "pgo_build": "False", 09:37:34 INFO - "basedir": "/builds/slave/test", 09:37:34 INFO - "buildnumber": 49, 09:37:34 INFO - "slavename": "t-yosemite-r7-0079", 09:37:34 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:37:34 INFO - "platform": "yosemite_r7", 09:37:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:37:34 INFO - "branch": "mozilla-central", 09:37:34 INFO - "repo_path": "mozilla-central", 09:37:34 INFO - "revision": "6d4525b5aaa834ea4db8d8eab8087d86e34cf57a", 09:37:34 INFO - "stage_platform": "macosx64", 09:37:34 INFO - "builduid": "0bfb350148674723b6f03a0744eb8853", 09:37:34 INFO - "slavebuilddir": "test" 09:37:34 INFO - } 09:37:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:37:34 INFO - Running post-action listener: _resource_record_post_action 09:37:34 INFO - [mozharness: 2017-05-04 16:37:34.577241Z] Finished read-buildbot-config step (success) 09:37:34 INFO - [mozharness: 2017-05-04 16:37:34.577343Z] Running download-and-extract step. 09:37:34 INFO - Running pre-action listener: _resource_record_pre_action 09:37:34 INFO - Running main action method: download_and_extract 09:37:34 INFO - mkdir: /builds/slave/test/build/tests 09:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:34 INFO - trying https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/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:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/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:37:35 INFO - Downloaded 1325 bytes. 09:37:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:37:35 INFO - Using the following test package requirements: 09:37:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:37:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:37:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:37:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:37:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'jsshell-mac.zip'], 09:37:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:37:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:37:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:37:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:37:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:37:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:37:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:37:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:35 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:37 INFO - Content-Length response header: 52700983 09:37:37 INFO - Bytes received: 52700983 09:37:53 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:37:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:53 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:55 INFO - Content-Length response header: 52700983 09:37:55 INFO - Bytes received: 52700983 09:38:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:38:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:38:11 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:38:12 INFO - Content-Length response header: 14408809 09:38:12 INFO - Bytes received: 14408809 09:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:12 INFO - trying https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:12 INFO - Downloading https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:38:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/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:38:14 INFO - Downloaded 70878346 bytes. 09:38:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:14 INFO - mkdir: /builds/slave/test/properties 09:38:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:14 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:14 INFO - Contents: 09:38:14 INFO - build_url:https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:15 INFO - Running post-action listener: _resource_record_post_action 09:38:15 INFO - Running post-action listener: set_extra_try_arguments 09:38:15 INFO - [mozharness: 2017-05-04 16:38:15.419819Z] Finished download-and-extract step (success) 09:38:15 INFO - [mozharness: 2017-05-04 16:38:15.419943Z] Running populate-webroot step. 09:38:15 INFO - Running pre-action listener: _resource_record_pre_action 09:38:15 INFO - Running main action method: populate_webroot 09:38:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:15 INFO - '--tppagecycles', 09:38:15 INFO - '7'], 09:38:15 INFO - 'tests': ['tsvgm']}}, 09:38:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['damp', 'tps']}, 09:38:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'tests': ['damp', 'tps']}, 09:38:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['dromaeo_dom']}, 09:38:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:38:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:38:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['a11yr', 09:38:15 INFO - 'ts_paint', 09:38:15 INFO - 'tpaint', 09:38:15 INFO - 'sessionrestore', 09:38:15 INFO - 'sessionrestore_no_auto_restore', 09:38:15 INFO - 'tabpaint']}, 09:38:15 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:38:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:38:15 INFO - 'bloom_basic_ref']}, 09:38:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['tsvgx', 09:38:15 INFO - 'tsvgr_opacity', 09:38:15 INFO - 'tart', 09:38:15 INFO - 'tscrollx', 09:38:15 INFO - 'cart', 09:38:15 INFO - 'tsvg_static']}, 09:38:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:15 INFO - 'tsvgr_opacity', 09:38:15 INFO - 'tart', 09:38:15 INFO - 'tscrollx', 09:38:15 INFO - 'cart', 09:38:15 INFO - 'tsvg_static']}, 09:38:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'talos_options': ['--disable-e10s'], 09:38:15 INFO - 'tests': ['tp5o']}, 09:38:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:38:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'talos_options': ['--disable-e10s', 09:38:15 INFO - '--xperf_path', 09:38:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:15 INFO - 'tests': ['tp5n']}, 09:38:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:38:15 INFO - 'talos_options': ['--xperf_path', 09:38:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:15 INFO - 'tests': ['tp5n']}}, 09:38:15 INFO - 'talos.zip': {'path': '', 09:38:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:15 INFO - Running post-action listener: _resource_record_post_action 09:38:15 INFO - [mozharness: 2017-05-04 16:38:15.439335Z] Finished populate-webroot step (success) 09:38:15 INFO - [mozharness: 2017-05-04 16:38:15.439440Z] Running create-virtualenv step. 09:38:15 INFO - Running pre-action listener: _resource_record_pre_action 09:38:15 INFO - Running main action method: create_virtualenv 09:38:15 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:15 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:38:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:15 INFO - Using real prefix '/tools/python27' 09:38:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:16 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:19 INFO - Installing pip.................done. 09:38:19 INFO - Return code: 0 09:38:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:38:19 INFO - Reading from file tmpfile_stdout 09:38:19 INFO - Output received: 09:38:19 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:38:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:19 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:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:19 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:38:19 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:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 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:38:19 INFO - Cleaning up... 09:38:19 INFO - Return code: 0 09:38:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38:19 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:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:19 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:38:19 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:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 INFO - Downloading/unpacking psutil>=3.1.1 09:38:19 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:38:19 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:38:19 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:38:19 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:38:19 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:38:19 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:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:22 INFO - Installing collected packages: psutil 09:38:22 INFO - Running setup.py install for psutil 09:38:22 INFO - building 'psutil._psutil_osx' extension 09:38:22 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:38:23 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:38:23 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:38:23 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:38:23 INFO - building 'psutil._psutil_posix' extension 09:38:23 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:38:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:23 INFO - ^ 09:38:23 INFO - 1 warning generated. 09:38:23 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:38:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:23 INFO - Successfully installed psutil 09:38:23 INFO - Cleaning up... 09:38:23 INFO - Return code: 0 09:38:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:23 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:38:23 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:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:23 INFO - 'HOME': '/Users/cltbld', 09:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:23 INFO - 'LOGNAME': 'cltbld', 09:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:23 INFO - 'MOZ_NO_REMOTE': '1', 09:38:23 INFO - 'NO_EM_RESTART': '1', 09:38:23 INFO - 'PAGER': '/bin/cat', 09:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:23 INFO - 'PWD': '/builds/slave/test', 09:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:23 INFO - 'SHELL': '/bin/bash', 09:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:23 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:23 INFO - 'USER': 'cltbld', 09:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:23 INFO - 'XPC_FLAGS': '0x0', 09:38:23 INFO - 'XPC_SERVICE_NAME': '0', 09:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38:24 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:38:24 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:38:24 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:38:24 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:38:24 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:38:24 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:38:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:25 INFO - Installing collected packages: mozsystemmonitor 09:38:25 INFO - Running setup.py install for mozsystemmonitor 09:38:25 INFO - Successfully installed mozsystemmonitor 09:38:25 INFO - Cleaning up... 09:38:25 INFO - Return code: 0 09:38:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:25 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:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:25 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:38:25 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:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:25 INFO - 'HOME': '/Users/cltbld', 09:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PAGER': '/bin/cat', 09:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:25 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - 'XPC_FLAGS': '0x0', 09:38:25 INFO - 'XPC_SERVICE_NAME': '0', 09:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:25 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:38:25 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:38:25 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:38:25 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:38:25 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:38:25 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:38:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:27 INFO - warning: no files found matching '*.txt' 09:38:27 INFO - no previously-included directories found matching 'build' 09:38:27 INFO - no previously-included directories found matching 'dist' 09:38:27 INFO - no previously-included directories found matching '.git*' 09:38:27 INFO - Installing collected packages: jsonschema, functools32 09:38:27 INFO - Running setup.py install for functools32 09:38:27 INFO - warning: no files found matching '*.txt' 09:38:27 INFO - no previously-included directories found matching 'build' 09:38:27 INFO - no previously-included directories found matching 'dist' 09:38:27 INFO - no previously-included directories found matching '.git*' 09:38:27 INFO - Successfully installed jsonschema functools32 09:38:27 INFO - Cleaning up... 09:38:28 INFO - Return code: 0 09:38:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:28 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:38:28 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:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:28 INFO - 'HOME': '/Users/cltbld', 09:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:28 INFO - 'LOGNAME': 'cltbld', 09:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:28 INFO - 'MOZ_NO_REMOTE': '1', 09:38:28 INFO - 'NO_EM_RESTART': '1', 09:38:28 INFO - 'PAGER': '/bin/cat', 09:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:28 INFO - 'PWD': '/builds/slave/test', 09:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:28 INFO - 'SHELL': '/bin/bash', 09:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:28 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:28 INFO - 'USER': 'cltbld', 09:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:28 INFO - 'XPC_FLAGS': '0x0', 09:38:28 INFO - 'XPC_SERVICE_NAME': '0', 09:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:38:28 INFO - Cleaning up... 09:38:28 INFO - Return code: 0 09:38:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:28 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:38:28 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:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:28 INFO - 'HOME': '/Users/cltbld', 09:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:28 INFO - 'LOGNAME': 'cltbld', 09:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:28 INFO - 'MOZ_NO_REMOTE': '1', 09:38:28 INFO - 'NO_EM_RESTART': '1', 09:38:28 INFO - 'PAGER': '/bin/cat', 09:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:28 INFO - 'PWD': '/builds/slave/test', 09:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:28 INFO - 'SHELL': '/bin/bash', 09:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:28 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:28 INFO - 'USER': 'cltbld', 09:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:28 INFO - 'XPC_FLAGS': '0x0', 09:38:28 INFO - 'XPC_SERVICE_NAME': '0', 09:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:29 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:38:29 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:38:29 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:38:29 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:38:29 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:38:29 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:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Downloading docopt-0.6.1.tar.gz 09:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:30 INFO - Running setup.py install for blobuploader 09:38:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:30 INFO - Running setup.py install for requests 09:38:31 INFO - Running setup.py install for docopt 09:38:31 INFO - Successfully installed blobuploader requests docopt 09:38:31 INFO - Cleaning up... 09:38:31 INFO - Return code: 0 09:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:31 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:31 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:38:31 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:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:31 INFO - 'HOME': '/Users/cltbld', 09:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:31 INFO - 'LOGNAME': 'cltbld', 09:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:31 INFO - 'MOZ_NO_REMOTE': '1', 09:38:31 INFO - 'NO_EM_RESTART': '1', 09:38:31 INFO - 'PAGER': '/bin/cat', 09:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:31 INFO - 'PWD': '/builds/slave/test', 09:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:31 INFO - 'SHELL': '/bin/bash', 09:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:31 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:31 INFO - 'USER': 'cltbld', 09:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:31 INFO - 'XPC_FLAGS': '0x0', 09:38:31 INFO - 'XPC_SERVICE_NAME': '0', 09:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:31 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-KwSprw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-GCxNj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-lmGxAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-J0WK_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1rNVdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-NvgzbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-NzpH19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-UlKjMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-VwdmK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-3CciMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:32 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-BvbGDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-o0qs8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-D0UhSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-AGzTuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-KY8qkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-fs5x8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:33 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-U9ks5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:33 INFO - Running setup.py install for manifestparser 09:38:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:33 INFO - Running setup.py install for mozcrash 09:38:33 INFO - Running setup.py install for mozdebug 09:38:34 INFO - Running setup.py install for mozdevice 09:38:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozfile 09:38:34 INFO - Running setup.py install for mozhttpd 09:38:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozinfo 09:38:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozInstall 09:38:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozleak 09:38:34 INFO - Running setup.py install for mozlog 09:38:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for moznetwork 09:38:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozprocess 09:38:35 INFO - Running setup.py install for mozprofile 09:38:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozrunner 09:38:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozscreenshot 09:38:35 INFO - Running setup.py install for moztest 09:38:36 INFO - Running setup.py install for mozversion 09:38:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:36 INFO - Cleaning up... 09:38:36 INFO - Return code: 0 09:38:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:36 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:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:36 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:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:36 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:36 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:38:36 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:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:36 INFO - 'HOME': '/Users/cltbld', 09:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:36 INFO - 'LOGNAME': 'cltbld', 09:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:36 INFO - 'MOZ_NO_REMOTE': '1', 09:38:36 INFO - 'NO_EM_RESTART': '1', 09:38:36 INFO - 'PAGER': '/bin/cat', 09:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:36 INFO - 'PWD': '/builds/slave/test', 09:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:36 INFO - 'SHELL': '/bin/bash', 09:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:36 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:36 INFO - 'USER': 'cltbld', 09:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:36 INFO - 'XPC_FLAGS': '0x0', 09:38:36 INFO - 'XPC_SERVICE_NAME': '0', 09:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:36 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-bGOjKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:36 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:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:36 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-33qNCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:36 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:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:36 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ZHH0z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:36 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:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:36 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-VyA3_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:36 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-QNvR4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ZtsOW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-se2dL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-RqTLFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-gBWs48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-yzfZiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-CSxj5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1532cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1lUHIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:37 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:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:37 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-xjJt0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:38 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:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:38 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ot3TDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:38 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:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:38 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ZXhT7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:38 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:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:38 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-iUOI65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:38 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:38:38 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:38:38 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:38:38 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:38:38 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:38 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:39 INFO - Downloading blessings-1.6.tar.gz 09:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:39 INFO - Installing collected packages: blessings 09:38:39 INFO - Running setup.py install for blessings 09:38:39 INFO - Successfully installed blessings 09:38:39 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:40 INFO - Reading from file tmpfile_stdout 09:38:40 INFO - Current package versions: 09:38:40 INFO - blessings == 1.6 09:38:40 INFO - blobuploader == 1.2.4 09:38:40 INFO - docopt == 0.6.1 09:38:40 INFO - functools32 == 3.2.3-2 09:38:40 INFO - jsonschema == 2.5.1 09:38:40 INFO - manifestparser == 1.1 09:38:40 INFO - mozInstall == 1.12 09:38:40 INFO - mozcrash == 1.0 09:38:40 INFO - mozdebug == 0.1 09:38:40 INFO - mozdevice == 0.50 09:38:40 INFO - mozfile == 1.2 09:38:40 INFO - mozhttpd == 0.7 09:38:40 INFO - mozinfo == 0.9 09:38:40 INFO - mozleak == 0.1 09:38:40 INFO - mozlog == 3.4 09:38:40 INFO - moznetwork == 0.27 09:38:40 INFO - mozprocess == 0.25 09:38:40 INFO - mozprofile == 0.28 09:38:40 INFO - mozrunner == 6.13 09:38:40 INFO - mozscreenshot == 0.1 09:38:40 INFO - mozsystemmonitor == 0.3 09:38:40 INFO - moztest == 0.8 09:38:40 INFO - mozversion == 1.4 09:38:40 INFO - psutil == 3.1.1 09:38:40 INFO - requests == 1.2.3 09:38:40 INFO - wsgiref == 0.1.2 09:38:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:40 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:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:40 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:38:40 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:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:40 INFO - 'HOME': '/Users/cltbld', 09:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:40 INFO - 'LOGNAME': 'cltbld', 09:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:40 INFO - 'MOZ_NO_REMOTE': '1', 09:38:40 INFO - 'NO_EM_RESTART': '1', 09:38:40 INFO - 'PAGER': '/bin/cat', 09:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:40 INFO - 'PWD': '/builds/slave/test', 09:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:40 INFO - 'SHELL': '/bin/bash', 09:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:40 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:40 INFO - 'USER': 'cltbld', 09:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:40 INFO - 'XPC_FLAGS': '0x0', 09:38:40 INFO - 'XPC_SERVICE_NAME': '0', 09:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 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:38:40 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:40 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:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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 0x109f6db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f70768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a2ca4740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, '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.4BD75j8FVF/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.MNjYAvgktQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:38:40 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:38:40 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:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:40 INFO - 'HOME': '/Users/cltbld', 09:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:40 INFO - 'LOGNAME': 'cltbld', 09:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:40 INFO - 'MOZ_NO_REMOTE': '1', 09:38:40 INFO - 'NO_EM_RESTART': '1', 09:38:40 INFO - 'PAGER': '/bin/cat', 09:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:40 INFO - 'PWD': '/builds/slave/test', 09:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:40 INFO - 'SHELL': '/bin/bash', 09:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:38:40 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:40 INFO - 'USER': 'cltbld', 09:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:40 INFO - 'XPC_FLAGS': '0x0', 09:38:40 INFO - 'XPC_SERVICE_NAME': '0', 09:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:40 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Running post-action listener: _resource_record_post_action 09:38:40 INFO - Running post-action listener: _start_resource_monitoring 09:38:40 INFO - Starting resource monitoring. 09:38:40 INFO - [mozharness: 2017-05-04 16:38:40.901835Z] Finished create-virtualenv step (success) 09:38:40 INFO - [mozharness: 2017-05-04 16:38:40.902789Z] Running install step. 09:38:40 INFO - Running pre-action listener: _resource_record_pre_action 09:38:40 INFO - Running main action method: install 09:38:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:41 INFO - Reading from file tmpfile_stdout 09:38:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:41 INFO - Reading from file tmpfile_stdout 09:38:41 INFO - Output received: 09:38:41 INFO - Usage: mozinstall [options] installer 09:38:41 INFO - Options: 09:38:41 INFO - -h, --help show this help message and exit 09:38:41 INFO - -d DEST, --destination=DEST 09:38:41 INFO - Directory to install application into. [default: 09:38:41 INFO - "/builds/slave/test"] 09:38:41 INFO - --app=APP Application being installed. [default: firefox] 09:38:41 INFO - mkdir: /builds/slave/test/build/application 09:38:41 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:38:41 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:39:01 INFO - Reading from file tmpfile_stdout 09:39:01 INFO - Output received: 09:39:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:39:01 INFO - Running post-action listener: _resource_record_post_action 09:39:01 INFO - [mozharness: 2017-05-04 16:39:01.160343Z] Finished install step (success) 09:39:01 INFO - [mozharness: 2017-05-04 16:39:01.160493Z] Running run-tests step. 09:39:01 INFO - Running pre-action listener: _resource_record_pre_action 09:39:01 INFO - Running main action method: run_tests 09:39:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:01 INFO - 2017-05-04 09:39:01.175 screenresolution[1632:7964] starting screenresolution argv=screenresolution get 09:39:01 INFO - 2017-05-04 09:39:01.177 screenresolution[1632:7964] Display 0: 1600x1200x32@60 09:39:01 INFO - 2017-05-04 09:39:01.183 screenresolution[1633:7966] starting screenresolution argv=screenresolution list 09:39:01 INFO - Available Modes on Display 0 09:39:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:01 INFO - Intel Iris: 09:39:01 INFO - Chipset Model: Intel Iris 09:39:01 INFO - Type: GPU 09:39:01 INFO - Bus: Built-In 09:39:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:01 INFO - Vendor: Intel (0x8086) 09:39:01 INFO - Device ID: 0x0a2e 09:39:01 INFO - Revision ID: 0x0009 09:39:01 INFO - Displays: 09:39:01 INFO - Display: 09:39:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:01 INFO - Display Serial Number: 1600x1200 60 09:39:01 INFO - Main Display: Yes 09:39:01 INFO - Mirror: Off 09:39:01 INFO - Online: Yes 09:39:01 INFO - Rotation: Supported 09:39:01 INFO - Return code: 0 09:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:01 INFO - Python 2.7.3 09:39:01 INFO - Return code: 0 09:39:01 INFO - grabbing minidump binary from tooltool 09:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:01 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 0x109d4bd30>, '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 0x109c71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d40be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:01 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:39:01 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:39:01 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:39:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:01 INFO - Return code: 0 09:39:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:01 INFO - ENV: RUST_BACKTRACE is now 1 09:39:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --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:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4BD75j8FVF/Render', 09:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:01 INFO - 'HOME': '/Users/cltbld', 09:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:01 INFO - 'LOGNAME': 'cltbld', 09:39:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:01 INFO - 'MOZ_NO_REMOTE': '1', 09:39:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:01 INFO - 'NO_EM_RESTART': '1', 09:39:01 INFO - 'PAGER': '/bin/cat', 09:39:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:01 INFO - 'PWD': '/builds/slave/test', 09:39:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:01 INFO - 'RUST_BACKTRACE': '1', 09:39:01 INFO - 'SHELL': '/bin/bash', 09:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNjYAvgktQ/Listeners', 09:39:01 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:01 INFO - 'USER': 'cltbld', 09:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:01 INFO - 'XPC_FLAGS': '0x0', 09:39:01 INFO - 'XPC_SERVICE_NAME': '0', 09:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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:39:01 INFO - mozversion application_buildid: 20170504080520 09:39:01 INFO - mozversion application_changeset: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a 09:39:01 INFO - mozversion application_display_name: Nightly 09:39:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:01 INFO - mozversion application_name: Firefox 09:39:01 INFO - mozversion application_remotingname: firefox 09:39:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:39:01 INFO - mozversion application_vendor: Mozilla 09:39:01 INFO - mozversion application_version: 55.0a1 09:39:01 INFO - mozversion platform_buildid: 20170504080520 09:39:01 INFO - mozversion platform_changeset: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a 09:39:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:39:01 INFO - mozversion platform_version: 55.0a1 09:39:01 INFO - using testdate: 1493915941 09:39:01 INFO - actual date: 1493915941 09:39:01 INFO - starting webserver on 'localhost:49240' 09:39:01 INFO - SUITE-START | Running 2 tests 09:39:01 INFO - TEST-START | dromaeo_css 09:39:01 INFO - operating with platform_type : mac_ 09:39:01 INFO - Initialising browser for dromaeo_css test... 09:39:01 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpvCHPR9/profile http://localhost:49240/getInfo.html) 09:39:04 INFO - PID 1643 | __metrics Screen width/height:1600/1200 09:39:04 INFO - PID 1643 | colorDepth:24 09:39:04 INFO - PID 1643 | Browser inner width/height: 1024/691 09:39:04 INFO - PID 1643 | __metrics 09:39:04 INFO - PID 1643 | 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:39:04 INFO - PID 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:05 INFO - TEST-INFO | 1643: exit 0 09:39:05 INFO - Browser initialized. 09:39:05 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:05 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpvCHPR9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:17 INFO - PID 1646 | RSS: Main: 239902720 09:39:17 INFO - PID 1646 | 09:42:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:42:25 INFO - PID 1646 | RSS: Main: 416858112 09:42:25 INFO - PID 1646 | 09:45:27 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:45:28 INFO - PID 1646 | RSS: Main: 370774016 09:45:28 INFO - PID 1646 | 09:48:30 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:48:31 INFO - PID 1646 | RSS: Main: 355184640 09:48:31 INFO - PID 1646 | 09:51:33 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:51:34 INFO - PID 1646 | RSS: Main: 327860224 09:51:34 INFO - PID 1646 | 09:54:37 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:54:38 INFO - PID 1646 | RSS: Main: 326230016 09:54:38 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:57:40 INFO - PID 1646 | RSS: Main: 372948992 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | __start_tp_report 09:57:40 INFO - PID 1646 | _x_x_mozilla_page_load 09:57:40 INFO - PID 1646 | _x_x_mozilla_page_load_details 09:57:40 INFO - PID 1646 | |i|pagename|runs| 09:57:40 INFO - PID 1646 | |0;dojo.html;3490.476428535718;3493.179102910817;3538.6638269364407;3542.875999340022;3559.5728512578494;5980.372060933604;6085.4827339676185;6111.572189946705;6118.541109416795;6121.846953826145;6083.695815209244;6114.969425152879;6132.417420345071;6134.539909506782;6140.140380346755;5234.581233501311;5238.842834714965;5260.894782104356;5274.525292723654;5347.839564813062;5362.785488580452;5371.060064488699;5374.408815030343;5382.407935127133;5386.811461598845;5379.38137114233;5386.73066346683;5388.703621300827;5395.271638328814;5398.595105367093;5349.893002139936;5354.651947623392;5355.7857685692525;5364.597655175839;5374.543163831079;5227.79088836446;5269.920951185735;5313.787448502056;5336.012837625051;5338.733063346817;6133.6319820810895;6159.291681456601;6212.409821066992;6224.564280500367;6238.532110091716;5271.525562699337;6509.544331896769;6534.117894084333;6536.7712130075215;6566.080748695186;6052.042047216948;6467.32093130224;6483.902741458882;6509.088727578142;6518.706658200393;5637.154426836008;5874.265716785402;6283.120363149117;6298.559100862895;6315;6818.249992500821;6819.556728812611;6825.726970921108;6825.761098361633;6830.692618832164;6278.0269058296035;6461.515386346043;6477.708503117371;6478.740850366027;6479.125318082093;6490.4807615390655;6523.575967562093;6557.737690492422;6617.8014659560295;6647.5346725729705;6559.901601475982;6568.244651865001;6574.80275591733;6575.736970521126;6578.04618330349;6511.251206111263;6519.576227545194;6549.6070235786;6577.934220657828;6592.670366481657;5194;5236.109861323508;5258.342707161605;5330.706811125426;5349.3580770307035;4682.227432473672;4683.601893838993;4690.554397332248;4718.363020992122;4740.146773580788;6246.437820596168;6247.250329960434;6253.093301471261;6256.217972753406;6264.060390941395;6404.359564043559;6431.485481161496;6615.272320044791;6672.032555279555;6687.364700353459;6215.844603884939;6216.098665693449;6223.0354295084335;6241.531885108635;6250.3749625037135;5885.3820348862755;5889.882202356014;5890.028145355968;5895.469407753237;5924.11138329254;6627.039079333374;6712.959491278664;6765.797026089225;6833.829154271183;6851.691673874588;6643.50173736962;6689.1972963244725;6698.196216454056;6704.4301234395625;6712.2616512184595;5732.197492350991;6771.492138089565;6793.830154245986;6806.897896531655;6878.55289406187;6361.713722882574;6441.323661015525;6448.161738973903;6562.704678289584;6579.572327798678;6580.5064620152725;6658.1344425224415;6674.132362792806;6682.565633233824;6685.097511836035;6154.230721159855;6465.450436712932;6469.191351081115;6469.935300647122;6482.935170648233;6225.190725205694;6282.089097080989;6291.370862913672;6309.432151106423;6312.495000399865;4723.866272094741;6166.229221347332;6180.783672571439;6285.685715714105;6381.617102973836;6434.131392261985;6456.2575303838785;6464.5798023128345;6467.644279564669;6482.254540727688;6304.18045654062;6336.651484168415;6346.111544383883;6365.936340636535;6367.968160159169 09:57:40 INFO - PID 1646 | |1;ext.html;788.2747871957799;802.4583406200815;938.9061093890612;1121.6354684727467;1130.0959232613907;32265.838670806643;35447.582096716134;35466.82266588667;35577.110572235644;35670.46494302581;37007.444888326696;37282.62717372825;37393.878183654466;37413.69052083178;37448.25103497929;5847.415258474161;8138.674453021872;8266.462679925815;8293.087760346358;8306.003279606455;5854.853628659275;7329;8235.670573177065;8245.79385515361;8263.545504997222;8240.21717936795;8310.085890552065;8312.8753068704;8328.875066874001;8369.58152092393;8204.425690201688;8270.297024752903;8270.297024752903;8293.543855087968;8297.377696672744;5740.368559458456;5839.562032847553;7291.52605080668;7389.519681220757;8019.67921283148;35120.648793511995;35375.4693679595;35394.823025884965;35461.82269088638;35475.1131221719;3435.5362026126404;3462.7056700180515;3467.5665541807275;3472.5659292588425;3473.878414255489;28371.716282837115;29483.968061117757;29487.262818429495;29504.557431638543;29545.409091818045;3090.273785660368;3131.906042818719;3158.952615710766;3177.189816596776;3181.443247431713;229038;276076;284853;285237;286165;37907.43138852919;37917.05207369783;40399.59600403958;47427.525724743;47447.81380465516;36883;47761.28358074632;47899.281510777364;47901;48025;13601.931990340183;13717.931410342884;13719.862801371859;13723.313834308445;13725.45098196081;11132;11172.162087843444;11186.944065279622;11191.384473854016;11198.888011119947;12406.007519398427;12410.441530131166;12424;12449.190802597803;12515.687107822196;5174.120399532088;5198.610104242197;5202.687838729688;5215.765290561933;5234.398044224963;815.6288888555692;910.4567757650718;985.7486340983147;989.2580564576567;1006.0995409108807;19050.61898762017;22646.75442850659;23541.764582353957;23864.164754233865;24066.75933240645;1099.741560733227;1109.7003808971533;1126.7352172239516;1130.7851508213414;1131.0442675938818;11821.940890295666;12013.939930300467;12067.336296503776;12089.697757555956;12094.032477401786;285817.5709121441;287974;288136;289460;289762;49619.25571116436;49748.751256243486;49846.51074424756;49893.501064989614;49914.001719965396;1380.3512349195862;1454.7745099509382;1532.6628141808785;1533.5706002319369;1544.5289186797909;21208.25771098004;23125.07499699993;23312.766872331737;23342;23440.062397504593;37152.44271335986;37172;37212.88361349164;37214.44178337273;37265.25469490649;249911;250376;250621;251133;252073;29147;29198.854005729834;29226.269343266587;33327.83336083401;33439.83280083584;40465.190696185324;41744;41812;41918.58081419147;42129;24451;27708.722912770616;28342.716572834008;28707.71292287134;29723;28574.85712571424;29283.975060873618;29391.265218368855;29697.25756856096;29746.55380169256;26845.731542684323;33544.32911341711;35129.82435087808;35231.11922201965;35235.82382088073;28505.28736781597;28646.713532864404;29411.705882940896;29512.557311639914;29812.552811708276;18391.482555233644;22445.663315050613;22629.43426414353;23245.535089298457;23330.65004024907 09:57:40 INFO - PID 1646 | |2;jquery.html;387.95538513071006;427.9075321677;437.2021061562649;441.40851259312524;453.93417954396637;16384.590385240375;20167.991600419977;20565.897170514163;20728.99999999998;21047.894760526175;20782.792172078276;20987.265445709403;21410.35768926931;21670.458238544004;22009.559808803853;5527.281453411051;5797;5961.821145365635;6187.257529096515;6202.9689851550675;6040.275166979968;6185.505159587222;6191.969040154793;6202.255729312489;6209.720562574673;5905.586608937376;6069.362716914727;6079.604825686339;6177.196964394622;6189.102580125879;4980.97509512452;6111.51107911366;6114.938850611481;6116.694165291739;6123.693815309239;5252.737363131866;5296.417394086648;5305.708186049766;5368;5369.355677318707;25158.748412515823;25252.747472525225;25290.74709252902;25291.367715807068;25663.358416039562;2228.64341705328;2298.9425264368524;2299.609066458689;2300.6894069300597;2302.021640802652;16473.258703358377;16557.58606034847;16788.748168777478;17161.970281783008;17191.742123868153;2059.98970005149;2101.2225476573767;2128.467883029243;2135.9252426165167;2144.1959265275523;110643;136338.31830840782;136467;137185;137302;10983.670489885317;11515.827262591069;11580.131490138274;11929.642110736691;12011.099167562468;14600.34298456572;15144.469943552143;15476.148811815234;15654.373825047098;15663.765043524356;8587.570621468902;8617.956910215535;8662.91337086621;8828.470291782514;8855;8202.26179643835;8213.507189568525;8283.25450709439;8366.288865446506;8370.58147092655;7203.063601731741;7343.302386273295;7354.70581176747;7358.889616655754;7367;2119.109973811003;2122.4906022554783;2127.0428307261795;2128.191287310812;2148.8280937525274;903.4172958441768;918.191991047887;972.2125078686287;973.9513024348754;977.3354119199013;20052.799472005387;20809.37571872846;20811;20832.791672083084;20946.476338091365;934.9018353072829;1008.6822650864954;1035.6064695415844;1038.5949479702922;1051.8632577764995;10010;10693.037626613643;10701.518431670593;10712.46437678113;10804.973527514874;499628;502385;514303;515743;530182;116603.00293102724;117045;117263;121029;121515;1172.8299396587447;1195.2768575011992;1216.5498765456837;1218.2081646929566;1220.0300760894931;17237.74143387825;17825.10874456298;17938.91030544839;18231;21331.8933405332;54980.175297371374;55430.16854747101;56308.718456407456;56372.43627563672;56513.71743141258;70534;71114.28885711076;72031.8772904309;72332;74741.62629186819;7140;8192.67229310821;8425.5787210638;8679.522626255617;8749.47503149813;13107.606771796862;13480.123791953492;13528.39122239482;13668.863311366758;13805.792913106512;10688.358698478116;10850.403227822546;11648.534058637899;11832.585859494877;11974;10790;10796.730081748019;10935;11096.611618593633;11112.777744444904;11555.133364997828;11662.70843228926;11910.583129590423;11967.76064478723;12058.638240852788;8328.916710832815;8594.570271486526;8621.91378086211;8663.566821658766;8765.342599305206;11514.30914145154;11656.417179141179;11851.407429628656;11891.28652280866;11948.2519020624 09:57:40 INFO - PID 1646 | |3;mootools.html;1220.304426476908;1304.4325718312534;1438.1514906205337;1444.8627380398862;1445.6458167748904;11031.44842757862;11514.539418423254;11518;11518.193726439153;11527.769444611113;8909.866352004708;9675.51622418878;11332.830007549894;11477.311361318327;11814.763704725921;2064.618045661554;2540.4284036091917;2552.5660637691635;2557.6291437741515;2560.50070236304;2477.170148000422;2511.849289042654;2516.4086439686657;2516.458961323315;2517.3706573356662;2409.9036038558525;2521.9747802521924;2522.5963845784677;2525.8484490930605;2526.8357556758842;2492.314613481289;2495.1391769839374;2523.3691577105815;2534.214393538009;2538.4415428605676;2426.7694569015916;2446.1683027770464;2454.950900981988;2458.139651122111;2462.482878595497;11191.608293709682;11431.771364572662;11440.084793216523;11444.94277528607;11486.655400338002;6492.3183065777885;6724.495662825308;6740.157480314961;6746.966265168643;6784.3554862288;3965.8017099144927;4294.420253265832;4367.388565600819;4367.628751556122;4370.759608221547;1203.3802591665212;1260.6281147061638;1307.3136603283258;1311.082242430293;1317.0451422718547;14835;15173.9378243523;16208.189590520427;16338.673226535522;16355.182240888027;5317.175837745156;5555.833325000176;5567.276254086943;5568.832935011957;5578.693171875586;11454;11466.426678666034;11489.080873530098;11495.252808567417;11535.423228838525;5386.596005299618;6459.160309159779;6472.611643301417;6488.740450381932;6497.15536980199;4602.939045534749;6171.321154672983;6200.224971878515;6230.937690623126;6244.219472565929;3894.2990261753157;4149.522804877417;4206.768627725504;4223.408722778813;4327.069680018811;1256.7989121740477;1259.515086691612;1262.5517941130765;1273.6943133648042;1275.528054619778;782.338923609549;791.2325044706625;793.8610743119931;797.565326896831;799.0770659887838;6525.575837570543;6736;6907.34380233877;6922.730043260901;6930.237673855871;894.6645008121955;919.3564504846634;923.7598224461689;933.6125507914276;934.1172591900724;3652.4521321801944;3700.296943580711;3776.244751049801;3819.255245227154;4179.270264105235;14516.346764395628;15311.61720956985;15323.61690957735;15344.232788360761;15371.538853834403;15006.02460840042;15203.847961520465;15204.923975380052;15206.391744330103;15231.086134831945;8143.42995990275;8458.534780587126;8463.74608761738;8677.392582519164;8693.217610415093;5888;6485.837854053686;6561.770338038145;6625.238097618642;6648.20221573395;12846.614601561969;13605.047646664638;13658.726825463635;13678.853863611226;13762.587122386345;13662.726745465234;13853.307334633026;13964.930175349058;13989.510367137102;14048.227347495576;8035.276825085771;8127.959360203161;8155.266026057446;8190.099089100313;8204.466709663853;10343;10576.84134737964;10577.312474689428;10585.470726463493;10588.99404556566;7849.882251766342;8659.653613855628;8686.565671716515;9069.818603627758;9226.215771659485;8653.740387788377;8733.519656418704;8841.823163536821;8842.425242359483;8868.822623547621;7799.18108598589;7976.322012628992;8053.35573154158;8077.959610201911;8080.515169089874;5363.383210930793;5935.643861368331;6021.7591296347655;6094;6095.085737139465;5973.850653733692;6076.908846367396;6162.476189523941;6214.40963108504;6345.4923606112325 09:57:40 INFO - PID 1646 | |4;prototype.html;326.8055506973351;350.7772564421593;356.77166613367433;405.4688358250694;405.7808783257041;5104.38747350318;5886.352501224873;5926.318473375563;5928.407159284069;5938.257717785277;5117.692938423697;5318.76065577049;5768.425669344703;5820.079373806634;5830.44610761978;4372.934405983913;4432.3794668746405;4438.467383913935;4449.84425545106;4466.33005049242;3638.490611314418;4025.8993525161814;4453.28747400416;4456.621187199092;4461.08547747711;4030.556638769733;4040.3939409088725;4102.405151253067;4273.465816772903;4447.933281000787;3612.819359032038;4392.824287028515;4412.889677758049;4422.329466542794;4458.910821783546;3384.3231353729107;3920.15716620925;3923.3325836519366;3923.7253392262555;3927.253821773854;4676.86649944538;5388.245645609618;5672.234248376458;5683.715814209273;5702.686352250625;4358.585934336233;4413.7793110344355;5089.389273287229;5114.923276150861;5129.820456284049;6126.571140020201;6307.4954003679595;6313.179286692746;6313.684315784192;6332.746690132389;3742.7567208131386;4136.91726165475;4176.582341765799;4213.199492096492;4223.303154979425;5766.481016708516;6319.905201421982;6414.93585064148;6508.5118616104455;6510.479161667055;3369.831508424569;4046.393041043867;4125.8968525786495;4154.148399578079;4158.958410415918;4144.626983571493;4302.548232435612;4311.223979683625;4330.415393921843;4353.717008394444;3777.527809023872;4638.6752927294765;5026.673266237683;5087.211482220262;5088.465711100355;5143.691378517301;6302.4958003359625;6310.8422289442215;6323.177986861679;6334.809955701337;1359.1301566997129;1453.8982271241123;1468.530070377469;1472.4551915791226;1481.6295926018495;856.498948115347;955.7467271173144;967.3953778888194;981.3915372468972;996.8405055191134;5994;6004.189434426296;6009.36901625323;6028.57799953999;6044.0438840150655;5326.375455486036;5859.974504461702;6139.907901381483;6335.321139897931;6584.736610535525;5697.809226832904;5826.941730582725;5874.647521148745;5917.497012753965;6006.819795406145;3325.3515564464697;3373.2578832656777;3377.2401209728105;3379.8339572847326;3387.3394688036087;5782.7686892523825;6064.545159112996;6488.416042556192;6598.571092878948;6664.900026499607;6563.540552161398;6568.638724870178;6576;6582.437840025171;6601.801945941629;6388.881945659491;6536.411722944958;6608.273089960197;6626.075750840383;6706.027625994204;6395.616263024047;6435.549511534147;6463.741450341934;6466.3856933593115;6478.902816457851;5339.46605339463;6145.938540614716;6310.337414571588;6343.492520598434;6353.777617783356;6606.3393660635475;6611.2727599963;6626.27111017778;6679.799606011828;6732.124823772878;3594.910127246735;4203.495580530356;4257.467816522934;4263.850765223202;4263.88937475939;4921.548143297664;6439.774607888701;6459.806205813833;6500;6513.54405191632;5430.484104010113;5878.97118004339;6225.346338634378;6256.874862502634;6265.8120256392385;4997.150484417585;5837.328707198726;6094.786682466092;6148.804260399955;6270.529710271657;4904.7302398367;6146.6004709693725;6148.538859585459;6171.56799024064;6228.4705800007505;5595.908797784393;6164.630122192682;6189.669221117481;6277.435030847246;6280.340564240689;6099.11562823397;6108.847278817887;6120.969395152996;6179.567430280017;6186.381361863778 09:57:40 INFO - PID 1646 | |5;yui.html;705.7318219076751;804.6258489802241;852.7612268564804;858.3476557816058;874.195739919274;15553.144577048259;16244.269007894643;16873;16878.746818797696;16882.15589220537;15708;15755.921220393882;15871.365145394198;15902.363905443797;15926.84073159268;833.4832403909585;846.4624963148415;849.2993280543545;849.9362547808939;852.1393392673414;1000.824855650262;1028.5268776362846;1034.0796690945065;1041.9374837509736;1057.8942105789436;1102.5755084292564;1104.100158370925;1104.7238190452388;1107.24153954541;1113.7939481195965;1101.3061771084165;1105.309181761399;1111.1721767283345;1112.360392774158;1112.4938153140301;793.9523628582303;825.7068740597061;826.9751907442786;828.772087675888;829.0176141272551;16542;16859;17004.234809433605;17037.82962170375;17319.65360692779;16165.191740412933;16283.185840707918;16736.58158546034;17209.913950430167;17570.824291757046;13670.794938075936;13842.515511957134;14257.643558911006;14466.204358760264;14506.274686265644;3515.103648569624;3562.323158599858;3563.483294922222;3565.6790888820133;3566.4115420955513;87901.56049219712;125929.37035314857;128673.35663321623;129847.35076324652;131511;11228.606998755004;11505.194636375541;13195.802062969064;13624.591262262147;13680.247586382646;24859;25365.239042828744;25592;26100.216993490227;26120.477590448085;8077.474964127313;8081.515109093473;8082.2725954664365;8092.6762929482165;8110.513369197749;3978.0507551299656;4001.299772539794;4018.3771515415156;4034.798260086984;4057.918841623151;4279.507856596469;4339.848105316299;4351.760653164043;4355.1289742051085;4360.847370342023;931.2736065868634;945.6359301668907;961.1253759079204;966.5408930757834;967.3663750243602;649.9415052645285;669.9933000669931;677.0555075669414;681.3697329969758;684.5858255755196;11186.664400068012;11724.237924534878;12022.097465964614;12216;12255.632331030083;625.6777759453885;642.8939225027818;661.2263651527724;681.3254877671169;688.0642326436043;4530.77346132692;4892.657513973988;4940.1601727706375;4949.876253093629;5134.666246693953;75344;75417;97232;99882.00117998727;103957.44063839049;88499.55750221337;90065;92179;93771;97455;78552.6072369646;78652.60673696594;79544;80190.19809801943;82835.17164828275;11891;12044.638660839839;12496.312702801433;12522.812157817458;12622.873771262537;37837;39700.404493933194;40325.596744032184;40472.99999999882;40746.796266019664;41625.751227463224;50031.499055028406;50332;51387;51713.741431292605;15754.842451575796;16063.518094457186;16347.509574712778;16442.424515141913;16534.338626454806;17294.308227671256;17405.738913916048;18099.638007240046;18112.72830907511;18270;16306.510804675878;16345.427910023092;16625.168741563117;16814.24335904863;16831.07429091412;12899.355032248537;15595.844041559893;15651.921740391224;15711.685766284381;15757.369705211662;16061.036337819769;16342.182890855647;16434.589135271715;16499.17504124813;16559.089250090878;11562.479688413874;11641.53433862636;12088.456019478968;12212.57255996036;12315.815262770886;12476.313802740573;12857;12984.610461686165;13025.609231723063;13173.143745656502 09:57:40 INFO - PID 1646 | __end_tp_report 09:57:40 INFO - PID 1646 | __start_cc_report 09:57:40 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2347 09:57:40 INFO - PID 1646 | __end_cc_report 09:57:40 INFO - PID 1646 | __startTimestamp1493917060662__endTimestamp 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | ------- Summary: start ------- 09:57:40 INFO - PID 1646 | Number of tests: 6 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6056.95 Median:6283.12 stddev:712.52 (11.3%) stddev-sans-first:685.67 09:57:40 INFO - PID 1646 | Values: 3490.5 3493.2 3538.7 3542.9 3559.6 5980.4 6085.5 6111.6 6118.5 6121.8 6083.7 6115.0 6132.4 6134.5 6140.1 5234.6 5238.8 5260.9 5274.5 5347.8 5362.8 5371.1 5374.4 5382.4 5386.8 5379.4 5386.7 5388.7 5395.3 5398.6 5349.9 5354.7 5355.8 5364.6 5374.5 5227.8 5269.9 5313.8 5336.0 5338.7 6133.6 6159.3 6212.4 6224.6 6238.5 5271.5 6509.5 6534.1 6536.8 6566.1 6052.0 6467.3 6483.9 6509.1 6518.7 5637.2 5874.3 6283.1 6298.6 6315.0 6818.2 6819.6 6825.7 6825.8 6830.7 6278.0 6461.5 6477.7 6478.7 6479.1 6490.5 6523.6 6557.7 6617.8 6647.5 6559.9 6568.2 6574.8 6575.7 6578.0 6511.3 6519.6 6549.6 6577.9 6592.7 5194.0 5236.1 5258.3 5330.7 5349.4 4682.2 4683.6 4690.6 4718.4 4740.1 6246.4 6247.3 6253.1 6256.2 6264.1 6404.4 6431.5 6615.3 6672.0 6687.4 6215.8 6216.1 6223.0 6241.5 6250.4 5885.4 5889.9 5890.0 5895.5 5924.1 6627.0 6713.0 6765.8 6833.8 6851.7 6643.5 6689.2 6698.2 6704.4 6712.3 5732.2 6771.5 6793.8 6806.9 6878.6 6361.7 6441.3 6448.2 6562.7 6579.6 6580.5 6658.1 6674.1 6682.6 6685.1 6154.2 6465.5 6469.2 6469.9 6482.9 6225.2 6282.1 6291.4 6309.4 6312.5 4723.9 6166.2 6180.8 6285.7 6381.6 6434.1 6456.3 6464.6 6467.6 6482.3 6304.2 6336.7 6346.1 6365.9 6368.0 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:41306.77 Median:3179.32 stddev:70998.58 (2233.1%) stddev-sans-first:71132.57 09:57:40 INFO - PID 1646 | Values: 788.3 802.5 938.9 1121.6 1130.1 32265.8 35447.6 35466.8 35577.1 35670.5 37007.4 37282.6 37393.9 37413.7 37448.3 5847.4 8138.7 8266.5 8293.1 8306.0 5854.9 7329.0 8235.7 8245.8 8263.5 8240.2 8310.1 8312.9 8328.9 8369.6 8204.4 8270.3 8270.3 8293.5 8297.4 5740.4 5839.6 7291.5 7389.5 8019.7 35120.6 35375.5 35394.8 35461.8 35475.1 3435.5 3462.7 3467.6 3472.6 3473.9 28371.7 29484.0 29487.3 29504.6 29545.4 3090.3 3131.9 3159.0 3177.2 3181.4 229038.0 276076.0 284853.0 285237.0 286165.0 37907.4 37917.1 40399.6 47427.5 47447.8 36883.0 47761.3 47899.3 47901.0 48025.0 13601.9 13717.9 13719.9 13723.3 13725.5 11132.0 11172.2 11186.9 11191.4 11198.9 12406.0 12410.4 12424.0 12449.2 12515.7 5174.1 5198.6 5202.7 5215.8 5234.4 815.6 910.5 985.7 989.3 1006.1 19050.6 22646.8 23541.8 23864.2 24066.8 1099.7 1109.7 1126.7 1130.8 1131.0 11821.9 12013.9 12067.3 12089.7 12094.0 285817.6 287974.0 288136.0 289460.0 289762.0 49619.3 49748.8 49846.5 49893.5 49914.0 1380.4 1454.8 1532.7 1533.6 1544.5 21208.3 23125.1 23312.8 23342.0 23440.1 37152.4 37172.0 37212.9 37214.4 37265.3 249911.0 250376.0 250621.0 251133.0 252073.0 29147.0 29198.9 29226.3 33327.8 33439.8 40465.2 41744.0 41812.0 41918.6 42129.0 24451.0 27708.7 28342.7 28707.7 29723.0 28574.9 29284.0 29391.3 29697.3 29746.6 26845.7 33544.3 35129.8 35231.1 35235.8 28505.3 28646.7 29411.7 29512.6 29812.6 18391.5 22445.7 22629.4 23245.5 23330.7 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:33082.12 Median:2146.51 stddev:86432.86 (4026.7%) stddev-sans-first:86640.47 09:57:40 INFO - PID 1646 | Values: 388.0 427.9 437.2 441.4 453.9 16384.6 20168.0 20565.9 20729.0 21047.9 20782.8 20987.3 21410.4 21670.5 22009.6 5527.3 5797.0 5961.8 6187.3 6203.0 6040.3 6185.5 6192.0 6202.3 6209.7 5905.6 6069.4 6079.6 6177.2 6189.1 4981.0 6111.5 6114.9 6116.7 6123.7 5252.7 5296.4 5305.7 5368.0 5369.4 25158.7 25252.7 25290.7 25291.4 25663.4 2228.6 2298.9 2299.6 2300.7 2302.0 16473.3 16557.6 16788.7 17162.0 17191.7 2060.0 2101.2 2128.5 2135.9 2144.2 110643.0 136338.3 136467.0 137185.0 137302.0 10983.7 11515.8 11580.1 11929.6 12011.1 14600.3 15144.5 15476.1 15654.4 15663.8 8587.6 8618.0 8662.9 8828.5 8855.0 8202.3 8213.5 8283.3 8366.3 8370.6 7203.1 7343.3 7354.7 7358.9 7367.0 2119.1 2122.5 2127.0 2128.2 2148.8 903.4 918.2 972.2 974.0 977.3 20052.8 20809.4 20811.0 20832.8 20946.5 934.9 1008.7 1035.6 1038.6 1051.9 10010.0 10693.0 10701.5 10712.5 10805.0 499628.0 502385.0 514303.0 515743.0 530182.0 116603.0 117045.0 117263.0 121029.0 121515.0 1172.8 1195.3 1216.5 1218.2 1220.0 17237.7 17825.1 17938.9 18231.0 21331.9 54980.2 55430.2 56308.7 56372.4 56513.7 70534.0 71114.3 72031.9 72332.0 74741.6 7140.0 8192.7 8425.6 8679.5 8749.5 13107.6 13480.1 13528.4 13668.9 13805.8 10688.4 10850.4 11648.5 11832.6 11974.0 10790.0 10796.7 10935.0 11096.6 11112.8 11555.1 11662.7 11910.6 11967.8 12058.6 8328.9 8594.6 8621.9 8663.6 8765.3 11514.3 11656.4 11851.4 11891.3 11948.3 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:6975.78 Median:3797.75 stddev:4440.62 (116.9%) stddev-sans-first:4432.01 09:57:40 INFO - PID 1646 | Values: 1220.3 1304.4 1438.2 1444.9 1445.6 11031.4 11514.5 11518.0 11518.2 11527.8 8909.9 9675.5 11332.8 11477.3 11814.8 2064.6 2540.4 2552.6 2557.6 2560.5 2477.2 2511.8 2516.4 2516.5 2517.4 2409.9 2522.0 2522.6 2525.8 2526.8 2492.3 2495.1 2523.4 2534.2 2538.4 2426.8 2446.2 2455.0 2458.1 2462.5 11191.6 11431.8 11440.1 11444.9 11486.7 6492.3 6724.5 6740.2 6747.0 6784.4 3965.8 4294.4 4367.4 4367.6 4370.8 1203.4 1260.6 1307.3 1311.1 1317.0 14835.0 15173.9 16208.2 16338.7 16355.2 5317.2 5555.8 5567.3 5568.8 5578.7 11454.0 11466.4 11489.1 11495.3 11535.4 5386.6 6459.2 6472.6 6488.7 6497.2 4602.9 6171.3 6200.2 6230.9 6244.2 3894.3 4149.5 4206.8 4223.4 4327.1 1256.8 1259.5 1262.6 1273.7 1275.5 782.3 791.2 793.9 797.6 799.1 6525.6 6736.0 6907.3 6922.7 6930.2 894.7 919.4 923.8 933.6 934.1 3652.5 3700.3 3776.2 3819.3 4179.3 14516.3 15311.6 15323.6 15344.2 15371.5 15006.0 15203.8 15204.9 15206.4 15231.1 8143.4 8458.5 8463.7 8677.4 8693.2 5888.0 6485.8 6561.8 6625.2 6648.2 12846.6 13605.0 13658.7 13678.9 13762.6 13662.7 13853.3 13964.9 13989.5 14048.2 8035.3 8128.0 8155.3 8190.1 8204.5 10343.0 10576.8 10577.3 10585.5 10589.0 7849.9 8659.7 8686.6 9069.8 9226.2 8653.7 8733.5 8841.8 8842.4 8868.8 7799.2 7976.3 8053.4 8078.0 8080.5 5363.4 5935.6 6021.8 6094.0 6095.1 5973.9 6076.9 6162.5 6214.4 6345.5 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5035.46 Median:5823.51 stddev:1591.27 (27.3%) stddev-sans-first:1555.99 09:57:40 INFO - PID 1646 | Values: 326.8 350.8 356.8 405.5 405.8 5104.4 5886.4 5926.3 5928.4 5938.3 5117.7 5318.8 5768.4 5820.1 5830.4 4372.9 4432.4 4438.5 4449.8 4466.3 3638.5 4025.9 4453.3 4456.6 4461.1 4030.6 4040.4 4102.4 4273.5 4447.9 3612.8 4392.8 4412.9 4422.3 4458.9 3384.3 3920.2 3923.3 3923.7 3927.3 4676.9 5388.2 5672.2 5683.7 5702.7 4358.6 4413.8 5089.4 5114.9 5129.8 6126.6 6307.5 6313.2 6313.7 6332.7 3742.8 4136.9 4176.6 4213.2 4223.3 5766.5 6319.9 6414.9 6508.5 6510.5 3369.8 4046.4 4125.9 4154.1 4159.0 4144.6 4302.5 4311.2 4330.4 4353.7 3777.5 4638.7 5026.7 5087.2 5088.5 5143.7 6302.5 6310.8 6323.2 6334.8 1359.1 1453.9 1468.5 1472.5 1481.6 856.5 955.7 967.4 981.4 996.8 5994.0 6004.2 6009.4 6028.6 6044.0 5326.4 5860.0 6139.9 6335.3 6584.7 5697.8 5826.9 5874.6 5917.5 6006.8 3325.4 3373.3 3377.2 3379.8 3387.3 5782.8 6064.5 6488.4 6598.6 6664.9 6563.5 6568.6 6576.0 6582.4 6601.8 6388.9 6536.4 6608.3 6626.1 6706.0 6395.6 6435.5 6463.7 6466.4 6478.9 5339.5 6145.9 6310.3 6343.5 6353.8 6606.3 6611.3 6626.3 6679.8 6732.1 3594.9 4203.5 4257.5 4263.9 4263.9 4921.5 6439.8 6459.8 6500.0 6513.5 5430.5 5879.0 6225.3 6256.9 6265.8 4997.2 5837.3 6094.8 6148.8 6270.5 4904.7 6146.6 6148.5 6171.6 6228.5 5595.9 6164.6 6189.7 6277.4 6280.3 6099.1 6108.8 6121.0 6179.6 6186.4 09:57:40 INFO - PID 1646 | 09:57:40 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20969.88 Median:17488.28 stddev:29191.29 (166.9%) stddev-sans-first:29233.52 09:57:40 INFO - PID 1646 | Values: 705.7 804.6 852.8 858.3 874.2 15553.1 16244.3 16873.0 16878.7 16882.2 15708.0 15755.9 15871.4 15902.4 15926.8 833.5 846.5 849.3 849.9 852.1 1000.8 1028.5 1034.1 1041.9 1057.9 1102.6 1104.1 1104.7 1107.2 1113.8 1101.3 1105.3 1111.2 1112.4 1112.5 794.0 825.7 827.0 828.8 829.0 16542.0 16859.0 17004.2 17037.8 17319.7 16165.2 16283.2 16736.6 17209.9 17570.8 13670.8 13842.5 14257.6 14466.2 14506.3 3515.1 3562.3 3563.5 3565.7 3566.4 87901.6 125929.4 128673.4 129847.4 131511.0 11228.6 11505.2 13195.8 13624.6 13680.2 24859.0 25365.2 25592.0 26100.2 26120.5 8077.5 8081.5 8082.3 8092.7 8110.5 3978.1 4001.3 4018.4 4034.8 4057.9 4279.5 4339.8 4351.8 4355.1 4360.8 931.3 945.6 961.1 966.5 967.4 649.9 670.0 677.1 681.4 684.6 11186.7 11724.2 12022.1 12216.0 12255.6 625.7 642.9 661.2 681.3 688.1 4530.8 4892.7 4940.2 4949.9 5134.7 75344.0 75417.0 97232.0 99882.0 103957.4 88499.6 90065.0 92179.0 93771.0 97455.0 78552.6 78652.6 79544.0 80190.2 82835.2 11891.0 12044.6 12496.3 12522.8 12622.9 37837.0 39700.4 40325.6 40473.0 40746.8 41625.8 50031.5 50332.0 51387.0 51713.7 15754.8 16063.5 16347.5 16442.4 16534.3 17294.3 17405.7 18099.6 18112.7 18270.0 16306.5 16345.4 16625.2 16814.2 16831.1 12899.4 15595.8 15651.9 15711.7 15757.4 16061.0 16342.2 16434.6 16499.2 16559.1 11562.5 11641.5 12088.5 12212.6 12315.8 12476.3 12857.0 12984.6 13025.6 13173.1 09:57:40 INFO - PID 1646 | -------- Summary: end -------- 09:57:40 INFO - PID 1646 | 09:57:41 INFO - TEST-INFO | 1646: exit 0 09:57:41 INFO - TEST-OK | dromaeo_css | took 1119593ms 09:57:41 INFO - TEST-START | kraken 09:57:41 INFO - operating with platform_type : mac_ 09:57:41 INFO - Initialising browser for kraken test... 09:57:41 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpfAXXc_/profile http://localhost:49240/getInfo.html) 09:57:43 INFO - PID 1653 | __metrics Screen width/height:1600/1200 09:57:43 INFO - PID 1653 | colorDepth:24 09:57:43 INFO - PID 1653 | Browser inner width/height: 1024/691 09:57:43 INFO - PID 1653 | __metrics 09:57:43 INFO - PID 1653 | 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:57:43 INFO - PID 1653 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:44 INFO - TEST-INFO | 1653: exit 0 09:57:44 INFO - Browser initialized. 09:57:44 INFO - Running cycle 1/1 for kraken test... 09:57:44 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpfAXXc_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:56 INFO - PID 1654 | RSS: Main: 240836608 09:57:56 INFO - PID 1654 | 09:57:59 INFO - PID 1654 | 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:58:00 INFO - PID 1654 | RSS: Main: 308391936 09:58:00 INFO - PID 1654 | 09:58:02 INFO - PID 1654 | 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:58:03 INFO - PID 1654 | RSS: Main: 291078144 09:58:03 INFO - PID 1654 | 09:58:06 INFO - PID 1654 | 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:58:07 INFO - PID 1654 | RSS: Main: 381739008 09:58:07 INFO - PID 1654 | 09:58:09 INFO - PID 1654 | 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:58:10 INFO - PID 1654 | RSS: Main: 283869184 09:58:10 INFO - PID 1654 | 09:58:12 INFO - PID 1654 | 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:58:12 INFO - PID 1654 | RSS: Main: 287596544 09:58:12 INFO - PID 1654 | 09:58:15 INFO - PID 1654 | 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:58:16 INFO - PID 1654 | RSS: Main: 349655040 09:58:16 INFO - PID 1654 | 09:58:18 INFO - PID 1654 | 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:58:19 INFO - PID 1654 | RSS: Main: 364138496 09:58:19 INFO - PID 1654 | 09:58:22 INFO - PID 1654 | 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:58:22 INFO - PID 1654 | RSS: Main: 367824896 09:58:22 INFO - PID 1654 | 09:58:24 INFO - PID 1654 | 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:58:25 INFO - PID 1654 | RSS: Main: 325849088 09:58:25 INFO - PID 1654 | 09:58:26 INFO - PID 1654 | 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:58:27 INFO - PID 1654 | RSS: Main: 327229440 09:58:27 INFO - PID 1654 | 09:58:29 INFO - PID 1654 | 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:58:30 INFO - PID 1654 | RSS: Main: 328556544 09:58:30 INFO - PID 1654 | 09:58:33 INFO - PID 1654 | 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:58:34 INFO - PID 1654 | RSS: Main: 349138944 09:58:34 INFO - PID 1654 | 09:58:37 INFO - PID 1654 | 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:58:37 INFO - PID 1654 | RSS: Main: 341921792 09:58:37 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | 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:58:39 INFO - PID 1654 | RSS: Main: 384950272 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | __start_tp_report 09:58:39 INFO - PID 1654 | _x_x_mozilla_page_load 09:58:39 INFO - PID 1654 | _x_x_mozilla_page_load_details 09:58:39 INFO - PID 1654 | |i|pagename|runs| 09:58:39 INFO - PID 1654 | |0;ai-astar;110;111;110;113;111;111;110;110;110;110 09:58:39 INFO - PID 1654 | |1;audio-beat-detection;143;111;108;149;109;108;121;108;135;107 09:58:39 INFO - PID 1654 | |2;audio-dft;184;185;186;185;184;185;185;188;183;185 09:58:39 INFO - PID 1654 | |3;audio-fft;79;59;57;87;62;65;60;61;84;78 09:58:39 INFO - PID 1654 | |4;audio-oscillator;90;70;85;69;68;69;88;70;68;94 09:58:39 INFO - PID 1654 | |5;imaging-gaussian-blur;88;86;87;88;87;86;88;88;87;88 09:58:39 INFO - PID 1654 | |6;imaging-darkroom;94;94;94;94;94;94;94;94;94;93 09:58:39 INFO - PID 1654 | |7;imaging-desaturate;84;85;84;92;87;86;86;86;86;83 09:58:39 INFO - PID 1654 | |8;json-parse-financial;48;47;47;49;48;46;48;49;48;48 09:58:39 INFO - PID 1654 | |9;json-stringify-tinderbox;51;63;54;64;62;58;54;55;55;55 09:58:39 INFO - PID 1654 | |10;stanford-crypto-aes;83;82;71;98;94;103;107;106;82;81 09:58:39 INFO - PID 1654 | |11;stanford-crypto-ccm;142;135;130;138;144;148;142;140;215;157 09:58:39 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;185;181;181;185;219;183;195;236;236;186 09:58:39 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;68;69;76;97;98;73;69;67;67;68 09:58:39 INFO - PID 1654 | __end_tp_report 09:58:39 INFO - PID 1654 | __start_cc_report 09:58:39 INFO - PID 1654 | _x_x_mozilla_cycle_collect,2167 09:58:39 INFO - PID 1654 | __end_cc_report 09:58:39 INFO - PID 1654 | __startTimestamp1493917119933__endTimestamp 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | ------- Summary: start ------- 09:58:39 INFO - PID 1654 | Number of tests: 14 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.50 stddev:0.97 (0.9%) stddev-sans-first:1.00 09:58:39 INFO - PID 1654 | Values: 110.0 111.0 110.0 113.0 111.0 111.0 110.0 110.0 110.0 110.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:116.00 stddev:16.33 (14.1%) stddev-sans-first:15.02 09:58:39 INFO - PID 1654 | Values: 143.0 111.0 108.0 149.0 109.0 108.0 121.0 108.0 135.0 107.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.33 (0.7%) stddev-sans-first:1.36 09:58:39 INFO - PID 1654 | Values: 184.0 185.0 186.0 185.0 184.0 185.0 185.0 188.0 183.0 185.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:69.20 Median:71.50 stddev:11.47 (16.0%) stddev-sans-first:11.60 09:58:39 INFO - PID 1654 | Values: 79.0 59.0 57.0 87.0 62.0 65.0 60.0 61.0 84.0 78.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:10.70 (13.8%) stddev-sans-first:10.28 09:58:39 INFO - PID 1654 | Values: 90.0 70.0 85.0 69.0 68.0 69.0 88.0 70.0 68.0 94.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 09:58:39 INFO - PID 1654 | Values: 88.0 86.0 87.0 88.0 87.0 86.0 88.0 88.0 87.0 88.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:58:39 INFO - PID 1654 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:2.47 (2.9%) stddev-sans-first:2.52 09:58:39 INFO - PID 1654 | Values: 84.0 85.0 84.0 92.0 87.0 86.0 86.0 86.0 86.0 83.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:47.80 Median:48.00 stddev:0.92 (1.9%) stddev-sans-first:0.97 09:58:39 INFO - PID 1654 | Values: 48.0 47.0 47.0 49.0 48.0 46.0 48.0 49.0 48.0 48.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:56.50 stddev:4.43 (7.8%) stddev-sans-first:4.12 09:58:39 INFO - PID 1654 | Values: 51.0 63.0 54.0 64.0 62.0 58.0 54.0 55.0 55.0 55.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:82.00 stddev:12.51 (15.3%) stddev-sans-first:12.95 09:58:39 INFO - PID 1654 | Values: 83.0 82.0 71.0 98.0 94.0 103.0 107.0 106.0 82.0 81.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:143.00 stddev:24.27 (17.0%) stddev-sans-first:25.61 09:58:39 INFO - PID 1654 | Values: 142.0 135.0 130.0 138.0 144.0 148.0 142.0 140.0 215.0 157.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.70 Median:190.50 stddev:22.65 (11.9%) stddev-sans-first:23.48 09:58:39 INFO - PID 1654 | Values: 185.0 181.0 181.0 185.0 219.0 183.0 195.0 236.0 236.0 186.0 09:58:39 INFO - PID 1654 | 09:58:39 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:71.00 stddev:12.09 (17.0%) stddev-sans-first:12.54 09:58:39 INFO - PID 1654 | Values: 68.0 69.0 76.0 97.0 98.0 73.0 69.0 67.0 67.0 68.0 09:58:39 INFO - PID 1654 | -------- Summary: end -------- 09:58:39 INFO - PID 1654 | 09:58:41 INFO - TEST-INFO | 1654: exit 0 09:58:41 INFO - TEST-OK | kraken | took 59816ms 09:58:41 INFO - SUITE-END | took 1179s 09:58:41 INFO - Completed test suite (00:19:39) 09:58:41 INFO - javascript benchmark 09:58:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7632.757931638679, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3490.476428535718, 3493.179102910817, 3538.6638269364407, 3542.875999340022, 3559.5728512578494, 5980.372060933604, 6085.4827339676185, 6111.572189946705, 6118.541109416795, 6121.846953826145, 6083.695815209244, 6114.969425152879, 6132.417420345071, 6134.539909506782, 6140.140380346755, 5234.581233501311, 5238.842834714965, 5260.894782104356, 5274.525292723654, 5347.839564813062, 5362.785488580452, 5371.060064488699, 5374.408815030343, 5382.407935127133, 5386.811461598845, 5379.38137114233, 5386.73066346683, 5388.703621300827, 5395.271638328814, 5398.595105367093, 5349.893002139936, 5354.651947623392, 5355.7857685692525, 5364.597655175839, 5374.543163831079, 5227.79088836446, 5269.920951185735, 5313.787448502056, 5336.012837625051, 5338.733063346817, 6133.6319820810895, 6159.291681456601, 6212.409821066992, 6224.564280500367, 6238.532110091716, 5271.525562699337, 6509.544331896769, 6534.117894084333, 6536.7712130075215, 6566.080748695186, 6052.042047216948, 6467.32093130224, 6483.902741458882, 6509.088727578142, 6518.706658200393, 5637.154426836008, 5874.265716785402, 6283.120363149117, 6298.559100862895, 6315.0, 6818.249992500821, 6819.556728812611, 6825.726970921108, 6825.761098361633, 6830.692618832164, 6278.0269058296035, 6461.515386346043, 6477.708503117371, 6478.740850366027, 6479.125318082093, 6490.4807615390655, 6523.575967562093, 6557.737690492422, 6617.8014659560295, 6647.5346725729705, 6559.901601475982, 6568.244651865001, 6574.80275591733, 6575.736970521126, 6578.04618330349, 6511.251206111263, 6519.576227545194, 6549.6070235786, 6577.934220657828, 6592.670366481657, 5194.0, 5236.109861323508, 5258.342707161605, 5330.706811125426, 5349.3580770307035, 4682.227432473672, 4683.601893838993, 4690.554397332248, 4718.363020992122, 4740.146773580788, 6246.437820596168, 6247.250329960434, 6253.093301471261, 6256.217972753406, 6264.060390941395, 6404.359564043559, 6431.485481161496, 6615.272320044791, 6672.032555279555, 6687.364700353459, 6215.844603884939, 6216.098665693449, 6223.0354295084335, 6241.531885108635, 6250.3749625037135, 5885.3820348862755, 5889.882202356014, 5890.028145355968, 5895.469407753237, 5924.11138329254, 6627.039079333374, 6712.959491278664, 6765.797026089225, 6833.829154271183, 6851.691673874588, 6643.50173736962, 6689.1972963244725, 6698.196216454056, 6704.4301234395625, 6712.2616512184595, 5732.197492350991, 6771.492138089565, 6793.830154245986, 6806.897896531655, 6878.55289406187, 6361.713722882574, 6441.323661015525, 6448.161738973903, 6562.704678289584, 6579.572327798678, 6580.5064620152725, 6658.1344425224415, 6674.132362792806, 6682.565633233824, 6685.097511836035, 6154.230721159855, 6465.450436712932, 6469.191351081115, 6469.935300647122, 6482.935170648233, 6225.190725205694, 6282.089097080989, 6291.370862913672, 6309.432151106423, 6312.495000399865, 4723.866272094741, 6166.229221347332, 6180.783672571439, 6285.685715714105, 6381.617102973836, 6434.131392261985, 6456.2575303838785, 6464.5798023128345, 6467.644279564669, 6482.254540727688, 6304.18045654062, 6336.651484168415, 6346.111544383883, 6365.936340636535, 6367.968160159169], "value": 6010.453637483622, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.2747871957799, 802.4583406200815, 938.9061093890612, 1121.6354684727467, 1130.0959232613907, 32265.838670806643, 35447.582096716134, 35466.82266588667, 35577.110572235644, 35670.46494302581, 37007.444888326696, 37282.62717372825, 37393.878183654466, 37413.69052083178, 37448.25103497929, 5847.415258474161, 8138.674453021872, 8266.462679925815, 8293.087760346358, 8306.003279606455, 5854.853628659275, 7329.0, 8235.670573177065, 8245.79385515361, 8263.545504997222, 8240.21717936795, 8310.085890552065, 8312.8753068704, 8328.875066874001, 8369.58152092393, 8204.425690201688, 8270.297024752903, 8270.297024752903, 8293.543855087968, 8297.377696672744, 5740.368559458456, 5839.562032847553, 7291.52605080668, 7389.519681220757, 8019.67921283148, 35120.648793511995, 35375.4693679595, 35394.823025884965, 35461.82269088638, 35475.1131221719, 3435.5362026126404, 3462.7056700180515, 3467.5665541807275, 3472.5659292588425, 3473.878414255489, 28371.716282837115, 29483.968061117757, 29487.262818429495, 29504.557431638543, 29545.409091818045, 3090.273785660368, 3131.906042818719, 3158.952615710766, 3177.189816596776, 3181.443247431713, 229038.0, 276076.0, 284853.0, 285237.0, 286165.0, 37907.43138852919, 37917.05207369783, 40399.59600403958, 47427.525724743, 47447.81380465516, 36883.0, 47761.28358074632, 47899.281510777364, 47901.0, 48025.0, 13601.931990340183, 13717.931410342884, 13719.862801371859, 13723.313834308445, 13725.45098196081, 11132.0, 11172.162087843444, 11186.944065279622, 11191.384473854016, 11198.888011119947, 12406.007519398427, 12410.441530131166, 12424.0, 12449.190802597803, 12515.687107822196, 5174.120399532088, 5198.610104242197, 5202.687838729688, 5215.765290561933, 5234.398044224963, 815.6288888555692, 910.4567757650718, 985.7486340983147, 989.2580564576567, 1006.0995409108807, 19050.61898762017, 22646.75442850659, 23541.764582353957, 23864.164754233865, 24066.75933240645, 1099.741560733227, 1109.7003808971533, 1126.7352172239516, 1130.7851508213414, 1131.0442675938818, 11821.940890295666, 12013.939930300467, 12067.336296503776, 12089.697757555956, 12094.032477401786, 285817.5709121441, 287974.0, 288136.0, 289460.0, 289762.0, 49619.25571116436, 49748.751256243486, 49846.51074424756, 49893.501064989614, 49914.001719965396, 1380.3512349195862, 1454.7745099509382, 1532.6628141808785, 1533.5706002319369, 1544.5289186797909, 21208.25771098004, 23125.07499699993, 23312.766872331737, 23342.0, 23440.062397504593, 37152.44271335986, 37172.0, 37212.88361349164, 37214.44178337273, 37265.25469490649, 249911.0, 250376.0, 250621.0, 251133.0, 252073.0, 29147.0, 29198.854005729834, 29226.269343266587, 33327.83336083401, 33439.83280083584, 40465.190696185324, 41744.0, 41812.0, 41918.58081419147, 42129.0, 24451.0, 27708.722912770616, 28342.716572834008, 28707.71292287134, 29723.0, 28574.85712571424, 29283.975060873618, 29391.265218368855, 29697.25756856096, 29746.55380169256, 26845.731542684323, 33544.32911341711, 35129.82435087808, 35231.11922201965, 35235.82382088073, 28505.28736781597, 28646.713532864404, 29411.705882940896, 29512.557311639914, 29812.552811708276, 18391.482555233644, 22445.663315050613, 22629.43426414353, 23245.535089298457, 23330.65004024907], "value": 16442.783325717708, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.95538513071006, 427.9075321677, 437.2021061562649, 441.40851259312524, 453.93417954396637, 16384.590385240375, 20167.991600419977, 20565.897170514163, 20728.99999999998, 21047.894760526175, 20782.792172078276, 20987.265445709403, 21410.35768926931, 21670.458238544004, 22009.559808803853, 5527.281453411051, 5797.0, 5961.821145365635, 6187.257529096515, 6202.9689851550675, 6040.275166979968, 6185.505159587222, 6191.969040154793, 6202.255729312489, 6209.720562574673, 5905.586608937376, 6069.362716914727, 6079.604825686339, 6177.196964394622, 6189.102580125879, 4980.97509512452, 6111.51107911366, 6114.938850611481, 6116.694165291739, 6123.693815309239, 5252.737363131866, 5296.417394086648, 5305.708186049766, 5368.0, 5369.355677318707, 25158.748412515823, 25252.747472525225, 25290.74709252902, 25291.367715807068, 25663.358416039562, 2228.64341705328, 2298.9425264368524, 2299.609066458689, 2300.6894069300597, 2302.021640802652, 16473.258703358377, 16557.58606034847, 16788.748168777478, 17161.970281783008, 17191.742123868153, 2059.98970005149, 2101.2225476573767, 2128.467883029243, 2135.9252426165167, 2144.1959265275523, 110643.0, 136338.31830840782, 136467.0, 137185.0, 137302.0, 10983.670489885317, 11515.827262591069, 11580.131490138274, 11929.642110736691, 12011.099167562468, 14600.34298456572, 15144.469943552143, 15476.148811815234, 15654.373825047098, 15663.765043524356, 8587.570621468902, 8617.956910215535, 8662.91337086621, 8828.470291782514, 8855.0, 8202.26179643835, 8213.507189568525, 8283.25450709439, 8366.288865446506, 8370.58147092655, 7203.063601731741, 7343.302386273295, 7354.70581176747, 7358.889616655754, 7367.0, 2119.109973811003, 2122.4906022554783, 2127.0428307261795, 2128.191287310812, 2148.8280937525274, 903.4172958441768, 918.191991047887, 972.2125078686287, 973.9513024348754, 977.3354119199013, 20052.799472005387, 20809.37571872846, 20811.0, 20832.791672083084, 20946.476338091365, 934.9018353072829, 1008.6822650864954, 1035.6064695415844, 1038.5949479702922, 1051.8632577764995, 10010.0, 10693.037626613643, 10701.518431670593, 10712.46437678113, 10804.973527514874, 499628.0, 502385.0, 514303.0, 515743.0, 530182.0, 116603.00293102724, 117045.0, 117263.0, 121029.0, 121515.0, 1172.8299396587447, 1195.2768575011992, 1216.5498765456837, 1218.2081646929566, 1220.0300760894931, 17237.74143387825, 17825.10874456298, 17938.91030544839, 18231.0, 21331.8933405332, 54980.175297371374, 55430.16854747101, 56308.718456407456, 56372.43627563672, 56513.71743141258, 70534.0, 71114.28885711076, 72031.8772904309, 72332.0, 74741.62629186819, 7140.0, 8192.67229310821, 8425.5787210638, 8679.522626255617, 8749.47503149813, 13107.606771796862, 13480.123791953492, 13528.39122239482, 13668.863311366758, 13805.792913106512, 10688.358698478116, 10850.403227822546, 11648.534058637899, 11832.585859494877, 11974.0, 10790.0, 10796.730081748019, 10935.0, 11096.611618593633, 11112.777744444904, 11555.133364997828, 11662.70843228926, 11910.583129590423, 11967.76064478723, 12058.638240852788, 8328.916710832815, 8594.570271486526, 8621.91378086211, 8663.566821658766, 8765.342599305206, 11514.30914145154, 11656.417179141179, 11851.407429628656, 11891.28652280866, 11948.2519020624], "value": 10078.470032078745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1220.304426476908, 1304.4325718312534, 1438.1514906205337, 1444.8627380398862, 1445.6458167748904, 11031.44842757862, 11514.539418423254, 11518.0, 11518.193726439153, 11527.769444611113, 8909.866352004708, 9675.51622418878, 11332.830007549894, 11477.311361318327, 11814.763704725921, 2064.618045661554, 2540.4284036091917, 2552.5660637691635, 2557.6291437741515, 2560.50070236304, 2477.170148000422, 2511.849289042654, 2516.4086439686657, 2516.458961323315, 2517.3706573356662, 2409.9036038558525, 2521.9747802521924, 2522.5963845784677, 2525.8484490930605, 2526.8357556758842, 2492.314613481289, 2495.1391769839374, 2523.3691577105815, 2534.214393538009, 2538.4415428605676, 2426.7694569015916, 2446.1683027770464, 2454.950900981988, 2458.139651122111, 2462.482878595497, 11191.608293709682, 11431.771364572662, 11440.084793216523, 11444.94277528607, 11486.655400338002, 6492.3183065777885, 6724.495662825308, 6740.157480314961, 6746.966265168643, 6784.3554862288, 3965.8017099144927, 4294.420253265832, 4367.388565600819, 4367.628751556122, 4370.759608221547, 1203.3802591665212, 1260.6281147061638, 1307.3136603283258, 1311.082242430293, 1317.0451422718547, 14835.0, 15173.9378243523, 16208.189590520427, 16338.673226535522, 16355.182240888027, 5317.175837745156, 5555.833325000176, 5567.276254086943, 5568.832935011957, 5578.693171875586, 11454.0, 11466.426678666034, 11489.080873530098, 11495.252808567417, 11535.423228838525, 5386.596005299618, 6459.160309159779, 6472.611643301417, 6488.740450381932, 6497.15536980199, 4602.939045534749, 6171.321154672983, 6200.224971878515, 6230.937690623126, 6244.219472565929, 3894.2990261753157, 4149.522804877417, 4206.768627725504, 4223.408722778813, 4327.069680018811, 1256.7989121740477, 1259.515086691612, 1262.5517941130765, 1273.6943133648042, 1275.528054619778, 782.338923609549, 791.2325044706625, 793.8610743119931, 797.565326896831, 799.0770659887838, 6525.575837570543, 6736.0, 6907.34380233877, 6922.730043260901, 6930.237673855871, 894.6645008121955, 919.3564504846634, 923.7598224461689, 933.6125507914276, 934.1172591900724, 3652.4521321801944, 3700.296943580711, 3776.244751049801, 3819.255245227154, 4179.270264105235, 14516.346764395628, 15311.61720956985, 15323.61690957735, 15344.232788360761, 15371.538853834403, 15006.02460840042, 15203.847961520465, 15204.923975380052, 15206.391744330103, 15231.086134831945, 8143.42995990275, 8458.534780587126, 8463.74608761738, 8677.392582519164, 8693.217610415093, 5888.0, 6485.837854053686, 6561.770338038145, 6625.238097618642, 6648.20221573395, 12846.614601561969, 13605.047646664638, 13658.726825463635, 13678.853863611226, 13762.587122386345, 13662.726745465234, 13853.307334633026, 13964.930175349058, 13989.510367137102, 14048.227347495576, 8035.276825085771, 8127.959360203161, 8155.266026057446, 8190.099089100313, 8204.466709663853, 10343.0, 10576.84134737964, 10577.312474689428, 10585.470726463493, 10588.99404556566, 7849.882251766342, 8659.653613855628, 8686.565671716515, 9069.818603627758, 9226.215771659485, 8653.740387788377, 8733.519656418704, 8841.823163536821, 8842.425242359483, 8868.822623547621, 7799.18108598589, 7976.322012628992, 8053.35573154158, 8077.959610201911, 8080.515169089874, 5363.383210930793, 5935.643861368331, 6021.7591296347655, 6094.0, 6095.085737139465, 5973.850653733692, 6076.908846367396, 6162.476189523941, 6214.40963108504, 6345.4923606112325], "value": 5289.235144514955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.8055506973351, 350.7772564421593, 356.77166613367433, 405.4688358250694, 405.7808783257041, 5104.38747350318, 5886.352501224873, 5926.318473375563, 5928.407159284069, 5938.257717785277, 5117.692938423697, 5318.76065577049, 5768.425669344703, 5820.079373806634, 5830.44610761978, 4372.934405983913, 4432.3794668746405, 4438.467383913935, 4449.84425545106, 4466.33005049242, 3638.490611314418, 4025.8993525161814, 4453.28747400416, 4456.621187199092, 4461.08547747711, 4030.556638769733, 4040.3939409088725, 4102.405151253067, 4273.465816772903, 4447.933281000787, 3612.819359032038, 4392.824287028515, 4412.889677758049, 4422.329466542794, 4458.910821783546, 3384.3231353729107, 3920.15716620925, 3923.3325836519366, 3923.7253392262555, 3927.253821773854, 4676.86649944538, 5388.245645609618, 5672.234248376458, 5683.715814209273, 5702.686352250625, 4358.585934336233, 4413.7793110344355, 5089.389273287229, 5114.923276150861, 5129.820456284049, 6126.571140020201, 6307.4954003679595, 6313.179286692746, 6313.684315784192, 6332.746690132389, 3742.7567208131386, 4136.91726165475, 4176.582341765799, 4213.199492096492, 4223.303154979425, 5766.481016708516, 6319.905201421982, 6414.93585064148, 6508.5118616104455, 6510.479161667055, 3369.831508424569, 4046.393041043867, 4125.8968525786495, 4154.148399578079, 4158.958410415918, 4144.626983571493, 4302.548232435612, 4311.223979683625, 4330.415393921843, 4353.717008394444, 3777.527809023872, 4638.6752927294765, 5026.673266237683, 5087.211482220262, 5088.465711100355, 5143.691378517301, 6302.4958003359625, 6310.8422289442215, 6323.177986861679, 6334.809955701337, 1359.1301566997129, 1453.8982271241123, 1468.530070377469, 1472.4551915791226, 1481.6295926018495, 856.498948115347, 955.7467271173144, 967.3953778888194, 981.3915372468972, 996.8405055191134, 5994.0, 6004.189434426296, 6009.36901625323, 6028.57799953999, 6044.0438840150655, 5326.375455486036, 5859.974504461702, 6139.907901381483, 6335.321139897931, 6584.736610535525, 5697.809226832904, 5826.941730582725, 5874.647521148745, 5917.497012753965, 6006.819795406145, 3325.3515564464697, 3373.2578832656777, 3377.2401209728105, 3379.8339572847326, 3387.3394688036087, 5782.7686892523825, 6064.545159112996, 6488.416042556192, 6598.571092878948, 6664.900026499607, 6563.540552161398, 6568.638724870178, 6576.0, 6582.437840025171, 6601.801945941629, 6388.881945659491, 6536.411722944958, 6608.273089960197, 6626.075750840383, 6706.027625994204, 6395.616263024047, 6435.549511534147, 6463.741450341934, 6466.3856933593115, 6478.902816457851, 5339.46605339463, 6145.938540614716, 6310.337414571588, 6343.492520598434, 6353.777617783356, 6606.3393660635475, 6611.2727599963, 6626.27111017778, 6679.799606011828, 6732.124823772878, 3594.910127246735, 4203.495580530356, 4257.467816522934, 4263.850765223202, 4263.88937475939, 4921.548143297664, 6439.774607888701, 6459.806205813833, 6500.0, 6513.54405191632, 5430.484104010113, 5878.97118004339, 6225.346338634378, 6256.874862502634, 6265.8120256392385, 4997.150484417585, 5837.328707198726, 6094.786682466092, 6148.804260399955, 6270.529710271657, 4904.7302398367, 6146.6004709693725, 6148.538859585459, 6171.56799024064, 6228.4705800007505, 5595.908797784393, 6164.630122192682, 6189.669221117481, 6277.435030847246, 6280.340564240689, 6099.11562823397, 6108.847278817887, 6120.969395152996, 6179.567430280017, 6186.381361863778], "value": 4536.61107509601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.7318219076751, 804.6258489802241, 852.7612268564804, 858.3476557816058, 874.195739919274, 15553.144577048259, 16244.269007894643, 16873.0, 16878.746818797696, 16882.15589220537, 15708.0, 15755.921220393882, 15871.365145394198, 15902.363905443797, 15926.84073159268, 833.4832403909585, 846.4624963148415, 849.2993280543545, 849.9362547808939, 852.1393392673414, 1000.824855650262, 1028.5268776362846, 1034.0796690945065, 1041.9374837509736, 1057.8942105789436, 1102.5755084292564, 1104.100158370925, 1104.7238190452388, 1107.24153954541, 1113.7939481195965, 1101.3061771084165, 1105.309181761399, 1111.1721767283345, 1112.360392774158, 1112.4938153140301, 793.9523628582303, 825.7068740597061, 826.9751907442786, 828.772087675888, 829.0176141272551, 16542.0, 16859.0, 17004.234809433605, 17037.82962170375, 17319.65360692779, 16165.191740412933, 16283.185840707918, 16736.58158546034, 17209.913950430167, 17570.824291757046, 13670.794938075936, 13842.515511957134, 14257.643558911006, 14466.204358760264, 14506.274686265644, 3515.103648569624, 3562.323158599858, 3563.483294922222, 3565.6790888820133, 3566.4115420955513, 87901.56049219712, 125929.37035314857, 128673.35663321623, 129847.35076324652, 131511.0, 11228.606998755004, 11505.194636375541, 13195.802062969064, 13624.591262262147, 13680.247586382646, 24859.0, 25365.239042828744, 25592.0, 26100.216993490227, 26120.477590448085, 8077.474964127313, 8081.515109093473, 8082.2725954664365, 8092.6762929482165, 8110.513369197749, 3978.0507551299656, 4001.299772539794, 4018.3771515415156, 4034.798260086984, 4057.918841623151, 4279.507856596469, 4339.848105316299, 4351.760653164043, 4355.1289742051085, 4360.847370342023, 931.2736065868634, 945.6359301668907, 961.1253759079204, 966.5408930757834, 967.3663750243602, 649.9415052645285, 669.9933000669931, 677.0555075669414, 681.3697329969758, 684.5858255755196, 11186.664400068012, 11724.237924534878, 12022.097465964614, 12216.0, 12255.632331030083, 625.6777759453885, 642.8939225027818, 661.2263651527724, 681.3254877671169, 688.0642326436043, 4530.77346132692, 4892.657513973988, 4940.1601727706375, 4949.876253093629, 5134.666246693953, 75344.0, 75417.0, 97232.0, 99882.00117998727, 103957.44063839049, 88499.55750221337, 90065.0, 92179.0, 93771.0, 97455.0, 78552.6072369646, 78652.60673696594, 79544.0, 80190.19809801943, 82835.17164828275, 11891.0, 12044.638660839839, 12496.312702801433, 12522.812157817458, 12622.873771262537, 37837.0, 39700.404493933194, 40325.596744032184, 40472.99999999882, 40746.796266019664, 41625.751227463224, 50031.499055028406, 50332.0, 51387.0, 51713.741431292605, 15754.842451575796, 16063.518094457186, 16347.509574712778, 16442.424515141913, 16534.338626454806, 17294.308227671256, 17405.738913916048, 18099.638007240046, 18112.72830907511, 18270.0, 16306.510804675878, 16345.427910023092, 16625.168741563117, 16814.24335904863, 16831.07429091412, 12899.355032248537, 15595.844041559893, 15651.921740391224, 15711.685766284381, 15757.369705211662, 16061.036337819769, 16342.182890855647, 16434.589135271715, 16499.17504124813, 16559.089250090878, 11562.479688413874, 11641.53433862636, 12088.456019478968, 12212.57255996036, 12315.815262770886, 12476.313802740573, 12857.0, 12984.610461686165, 13025.609231723063, 13173.143745656502], "value": 8272.878029731757, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1447.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 113.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 108.0, 149.0, 109.0, 108.0, 121.0, 108.0, 135.0, 107.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 185.0, 184.0, 185.0, 185.0, 188.0, 183.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 57.0, 87.0, 62.0, 65.0, 60.0, 61.0, 84.0, 78.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 85.0, 69.0, 68.0, 69.0, 88.0, 70.0, 68.0, 94.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 88.0, 87.0, 86.0, 88.0, 88.0, 87.0, 88.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 92.0, 87.0, 86.0, 86.0, 86.0, 86.0, 83.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 47.0, 49.0, 48.0, 46.0, 48.0, 49.0, 48.0, 48.0], "value": 47.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 63.0, 54.0, 64.0, 62.0, 58.0, 54.0, 55.0, 55.0, 55.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 71.0, 98.0, 94.0, 103.0, 107.0, 106.0, 82.0, 81.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 135.0, 130.0, 138.0, 144.0, 148.0, 142.0, 140.0, 215.0, 157.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 181.0, 185.0, 219.0, 183.0, 195.0, 236.0, 236.0, 186.0], "value": 198.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 76.0, 97.0, 98.0, 73.0, 69.0, 67.0, 67.0, 68.0], "value": 75.2, "unit": "score"}]}]} 09:58:41 INFO - Return code: 0 09:58:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:41 INFO - # TBPL SUCCESS # 09:58:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:41 INFO - 2017-05-04 09:58:41.775 screenresolution[1659:10133] starting screenresolution argv=screenresolution get 09:58:41 INFO - 2017-05-04 09:58:41.778 screenresolution[1659:10133] Display 0: 1600x1200x32@60 09:58:41 INFO - 2017-05-04 09:58:41.784 screenresolution[1660:10136] starting screenresolution argv=screenresolution list 09:58:41 INFO - Available Modes on Display 0 09:58:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:42 INFO - Intel Iris: 09:58:42 INFO - Chipset Model: Intel Iris 09:58:42 INFO - Type: GPU 09:58:42 INFO - Bus: Built-In 09:58:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:42 INFO - Vendor: Intel (0x8086) 09:58:42 INFO - Device ID: 0x0a2e 09:58:42 INFO - Revision ID: 0x0009 09:58:42 INFO - Displays: 09:58:42 INFO - Display: 09:58:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:42 INFO - Display Serial Number: 1600x1200 60 09:58:42 INFO - Main Display: Yes 09:58:42 INFO - Mirror: Off 09:58:42 INFO - Online: Yes 09:58:42 INFO - Rotation: Supported 09:58:42 INFO - Return code: 0 09:58:42 INFO - Running post-action listener: _resource_record_post_action 09:58:42 INFO - [mozharness: 2017-05-04 16:58:42.026307Z] Finished run-tests step (success) 09:58:42 INFO - Running post-run listener: _resource_record_post_run 09:58:42 INFO - Total resource usage - Wall time: 1200s; CPU: 53.0%; Read bytes: 34319872; Write bytes: 508906496; Read time: 324; Write time: 1681 09:58:42 INFO - TinderboxPrint: CPU usage
53.3% 09:58:42 INFO - TinderboxPrint: I/O read bytes / time
34,319,872 / 324 09:58:42 INFO - TinderboxPrint: I/O write bytes / time
508,906,496 / 1,681 09:58:42 INFO - TinderboxPrint: CPU idle
2,242.2 (46.7%) 09:58:42 INFO - TinderboxPrint: CPU system
956.7 (19.9%) 09:58:42 INFO - TinderboxPrint: CPU user
1,602.7 (33.4%) 09:58:42 INFO - TinderboxPrint: Swap in / out
238,329,856 / 0 09:58:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208165376; Write bytes: 205387776; Read time: 16505; Write time: 415 09:58:42 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29052416; Write bytes: 296317952; Read time: 280; Write time: 1238 09:58:42 INFO - Running post-run listener: _upload_blobber_files 09:58:42 INFO - Blob upload gear active. 09:58:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:42 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:58:42 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:58:42 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:58:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:44 INFO - (blobuploader) - INFO - Done attempting. 09:58:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:45 INFO - (blobuploader) - INFO - Done attempting. 09:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:46 INFO - (blobuploader) - INFO - Done attempting. 09:58:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:48 INFO - (blobuploader) - INFO - Done attempting. 09:58:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:48 INFO - Return code: 0 09:58:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ddafb753e0c7456e6833aae9693ba31aaeed731a99a25108f0a5e264b7fbe62a9b777a4108f5449cd22827b42e871c8e2b8783d4d5e7fc0157a42aa38b83a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a269aa9acc51fcc36036b75751a9799a6d02b10e23ebd6cdde5f1df438d65b2c725154141cd66f9b2ec39535a329b4f8fc5743a33a3c2d47a8136b89b8f3aa85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ab791341ccbc8d7e1c387f338df01a103cf234523cfec6fc093d86744474c0172f73e1a32def9b0dc36a673127c568127b064c526ba62bc1e348e44e81e0921", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d92caf5060987454955b62016ffd06173036b15be83c75d58888f3714cc79f7ad630a4c026b391fad2c97554da49fa35cb3c625d9ca014fb91e93559dc1d6a98"} 09:58:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:48 INFO - Contents: 09:58:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ddafb753e0c7456e6833aae9693ba31aaeed731a99a25108f0a5e264b7fbe62a9b777a4108f5449cd22827b42e871c8e2b8783d4d5e7fc0157a42aa38b83a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a269aa9acc51fcc36036b75751a9799a6d02b10e23ebd6cdde5f1df438d65b2c725154141cd66f9b2ec39535a329b4f8fc5743a33a3c2d47a8136b89b8f3aa85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ab791341ccbc8d7e1c387f338df01a103cf234523cfec6fc093d86744474c0172f73e1a32def9b0dc36a673127c568127b064c526ba62bc1e348e44e81e0921", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d92caf5060987454955b62016ffd06173036b15be83c75d58888f3714cc79f7ad630a4c026b391fad2c97554da49fa35cb3c625d9ca014fb91e93559dc1d6a98"} 09:58:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:48 INFO - Copying logs to upload dir... 09:58:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.294080 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-04 09:58:48.222313) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:48.226244) ========= 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.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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/6ddafb753e0c7456e6833aae9693ba31aaeed731a99a25108f0a5e264b7fbe62a9b777a4108f5449cd22827b42e871c8e2b8783d4d5e7fc0157a42aa38b83a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a269aa9acc51fcc36036b75751a9799a6d02b10e23ebd6cdde5f1df438d65b2c725154141cd66f9b2ec39535a329b4f8fc5743a33a3c2d47a8136b89b8f3aa85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ab791341ccbc8d7e1c387f338df01a103cf234523cfec6fc093d86744474c0172f73e1a32def9b0dc36a673127c568127b064c526ba62bc1e348e44e81e0921", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d92caf5060987454955b62016ffd06173036b15be83c75d58888f3714cc79f7ad630a4c026b391fad2c97554da49fa35cb3c625d9ca014fb91e93559dc1d6a98"} build_url:https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011142 build_url: 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/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/6ddafb753e0c7456e6833aae9693ba31aaeed731a99a25108f0a5e264b7fbe62a9b777a4108f5449cd22827b42e871c8e2b8783d4d5e7fc0157a42aa38b83a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a269aa9acc51fcc36036b75751a9799a6d02b10e23ebd6cdde5f1df438d65b2c725154141cd66f9b2ec39535a329b4f8fc5743a33a3c2d47a8136b89b8f3aa85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ab791341ccbc8d7e1c387f338df01a103cf234523cfec6fc093d86744474c0172f73e1a32def9b0dc36a673127c568127b064c526ba62bc1e348e44e81e0921", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d92caf5060987454955b62016ffd06173036b15be83c75d58888f3714cc79f7ad630a4c026b391fad2c97554da49fa35cb3c625d9ca014fb91e93559dc1d6a98"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:48.261969) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:48.262341) ========= 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.4BD75j8FVF/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.MNjYAvgktQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.005008 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:48.311093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:58:48.311516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:58:48.311950) ========= ========= Total master_lag: 0.19 =========