builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0201 starttime: 1479737640.98 results: success (0) buildid: 20161121030224 builduid: 6a52e0bfbe7141c99c5ed5a0d8094d11 revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:00.978734) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:00.979238) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:00.979575) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.005713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.006310) ========= 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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-21 06:14:01-- 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% 171M=0s 2016-11-21 06:14:01 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.162729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.163036) ========= 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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.024890 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.211682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.212045) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --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 b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-21 06:14:01,309 truncating revision to first 12 chars 2016-11-21 06:14:01,310 Setting DEBUG logging. 2016-11-21 06:14:01,310 attempt 1/10 2016-11-21 06:14:01,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7f895c1dc2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 06:14:01,707 unpacking tar archive at: mozilla-central-b7f895c1dc2e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655684 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.890614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.890915) ========= script_repo_revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.891283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.891555) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.910016) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-21 06:14:01.910334) ========= /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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:02 INFO - MultiFileLogger online at 20161121 06:14:02 in /builds/slave/test 06:14:02 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 06:14:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:02 INFO - {'append_to_log': False, 06:14:02 INFO - 'base_work_dir': '/builds/slave/test', 06:14:02 INFO - 'blob_upload_branch': 'Firefox', 06:14:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:02 INFO - 'branch': 'Firefox', 06:14:02 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:02 INFO - 'config_files': ('talos/mac_config.py',), 06:14:02 INFO - 'default_actions': ('clobber', 06:14:02 INFO - 'read-buildbot-config', 06:14:02 INFO - 'download-and-extract', 06:14:02 INFO - 'populate-webroot', 06:14:02 INFO - 'create-virtualenv', 06:14:02 INFO - 'install', 06:14:02 INFO - 'run-tests'), 06:14:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:02 INFO - 'download_minidump_stackwalk': True, 06:14:02 INFO - 'download_symbols': 'ondemand', 06:14:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:02 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:02 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:02 INFO - 'installer_path': 'installer.exe', 06:14:02 INFO - 'log_level': 'info', 06:14:02 INFO - 'log_name': 'talos', 06:14:02 INFO - 'log_to_console': True, 06:14:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:14:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:14:02 INFO - 'opt_config_files': (), 06:14:02 INFO - 'pip_index': False, 06:14:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:02 INFO - 'cmd': ('bash', 06:14:02 INFO - '-c', 06:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:02 INFO - 'enabled': True, 06:14:02 INFO - 'halt_on_failure': False, 06:14:02 INFO - 'name': 'check_screen_resolution'},), 06:14:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:02 INFO - 'cmd': ('bash', 06:14:02 INFO - '-c', 06:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:02 INFO - 'enabled': True, 06:14:02 INFO - 'halt_on_failure': False, 06:14:02 INFO - 'name': 'check_screen_resolution'},), 06:14:02 INFO - 'run_cmd_checks_enabled': True, 06:14:02 INFO - 'sps_profile': False, 06:14:02 INFO - 'sps_profile_interval': 0, 06:14:02 INFO - 'suite': 'dromaeojs', 06:14:02 INFO - 'system_bits': '32', 06:14:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:02 INFO - 'title': 't-yosemite-r7-0201', 06:14:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:02 INFO - 'use_talos_json': True, 06:14:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:02 INFO - 'work_dir': 'build'} 06:14:02 INFO - [mozharness: 2016-11-21 14:14:02.117311Z] Running clobber step. 06:14:02 INFO - Running pre-action listener: _resource_record_pre_action 06:14:02 INFO - Running main action method: clobber 06:14:02 INFO - rmtree: /builds/slave/test/build 06:14:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:04 INFO - Running post-action listener: _resource_record_post_action 06:14:04 INFO - [mozharness: 2016-11-21 14:14:04.532079Z] Finished clobber step (success) 06:14:04 INFO - [mozharness: 2016-11-21 14:14:04.532293Z] Running read-buildbot-config step. 06:14:04 INFO - Running pre-action listener: _resource_record_pre_action 06:14:04 INFO - Running main action method: read_buildbot_config 06:14:04 INFO - Using buildbot properties: 06:14:04 INFO - { 06:14:04 INFO - "project": "", 06:14:04 INFO - "product": "firefox", 06:14:04 INFO - "script_repo_revision": "production", 06:14:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:14:04 INFO - "repository": "", 06:14:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:14:04 INFO - "buildid": "20161121030224", 06:14:04 INFO - "stage_platform": "macosx64", 06:14:04 INFO - "basedir": "/builds/slave/test", 06:14:04 INFO - "nightly_build": "True", 06:14:04 INFO - "buildnumber": 0, 06:14:04 INFO - "slavename": "t-yosemite-r7-0201", 06:14:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:14:04 INFO - "platform": "yosemite_r7", 06:14:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:14:04 INFO - "branch": "mozilla-central", 06:14:04 INFO - "repo_path": "mozilla-central", 06:14:04 INFO - "revision": "b7f895c1dc2e91530240efbf50ac063a0f8a9cb5", 06:14:04 INFO - "pgo_build": "False", 06:14:04 INFO - "builduid": "6a52e0bfbe7141c99c5ed5a0d8094d11", 06:14:04 INFO - "slavebuilddir": "test" 06:14:04 INFO - } 06:14:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:14:04 INFO - Running post-action listener: _resource_record_post_action 06:14:04 INFO - [mozharness: 2016-11-21 14:14:04.537796Z] Finished read-buildbot-config step (success) 06:14:04 INFO - [mozharness: 2016-11-21 14:14:04.537942Z] Running download-and-extract step. 06:14:04 INFO - Running pre-action listener: _resource_record_pre_action 06:14:04 INFO - Running main action method: download_and_extract 06:14:04 INFO - mkdir: /builds/slave/test/build/tests 06:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:04 INFO - trying https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:04 INFO - Downloading https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 06:14:05 INFO - Downloaded 1199 bytes. 06:14:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:05 INFO - Using the following test package requirements: 06:14:05 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:14:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:14:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'jsshell-mac.zip'], 06:14:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:14:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:14:05 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:14:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:14:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:05 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:14:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:06 INFO - Http code: 200 06:14:06 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 06:14:06 INFO - x-amz-cf-id: PX1gOlcdTS2VcyQcI3cu9usITetHpbtUre1rA5a8l5LDuM_iYlIhRA== 06:14:06 INFO - x-amz-version-id: ZgcLuuUorp7jZes0Ff_2MtNpgJS.wtCQ 06:14:06 INFO - Expected file size: 16265676 06:14:06 INFO - Obtained file size: 16265676 06:14:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:14 INFO - Http code: 200 06:14:14 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 06:14:14 INFO - x-amz-cf-id: QUsHlb4ExIlpawhgwyJeMYXWdBEWyQw3XbSOYF7YM_lF31p3qzpUfA== 06:14:14 INFO - x-amz-version-id: ZgcLuuUorp7jZes0Ff_2MtNpgJS.wtCQ 06:14:14 INFO - Expected file size: 16265676 06:14:14 INFO - Obtained file size: 16265676 06:14:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:14:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:14:20 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:14:21 INFO - Http code: 200 06:14:21 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 06:14:21 INFO - x-amz-cf-id: hdVRtMU2bDwJ39g5LY14i4g8nFGxT1NZleZVd6U51Sqmk0NdR0F4Dg== 06:14:21 INFO - x-amz-version-id: E5wDGCcJCipAP7qzIByHuaENYNJbST3P 06:14:21 INFO - Expected file size: 14409392 06:14:21 INFO - Obtained file size: 14409392 06:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:22 INFO - trying https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 06:14:25 INFO - Downloaded 107691321 bytes. 06:14:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:25 INFO - mkdir: /builds/slave/test/properties 06:14:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:25 INFO - Contents: 06:14:25 INFO - build_url:https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:25 INFO - Running post-action listener: _resource_record_post_action 06:14:25 INFO - Running post-action listener: set_extra_try_arguments 06:14:25 INFO - [mozharness: 2016-11-21 14:14:25.675457Z] Finished download-and-extract step (success) 06:14:25 INFO - [mozharness: 2016-11-21 14:14:25.675620Z] Running populate-webroot step. 06:14:25 INFO - Running pre-action listener: _resource_record_pre_action 06:14:25 INFO - Running main action method: populate_webroot 06:14:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:25 INFO - '--tppagecycles', 06:14:25 INFO - '7'], 06:14:25 INFO - 'tests': ['tsvgm']}}, 06:14:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['damp', 'tps']}, 06:14:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'tests': ['damp', 'tps']}, 06:14:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['dromaeo_dom']}, 06:14:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['basic_compositor_video']}, 06:14:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['a11yr', 06:14:25 INFO - 'ts_paint', 06:14:25 INFO - 'tpaint', 06:14:25 INFO - 'sessionrestore', 06:14:25 INFO - 'sessionrestore_no_auto_restore', 06:14:25 INFO - 'tabpaint']}, 06:14:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['tsvgx', 06:14:25 INFO - 'tsvgr_opacity', 06:14:25 INFO - 'tart', 06:14:25 INFO - 'tscrollx', 06:14:25 INFO - 'cart']}, 06:14:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:25 INFO - 'tsvgr_opacity', 06:14:25 INFO - 'tart', 06:14:25 INFO - 'tscrollx', 06:14:25 INFO - 'cart']}, 06:14:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'talos_options': ['--disable-e10s'], 06:14:25 INFO - 'tests': ['tp5o']}, 06:14:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:14:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'talos_options': ['--disable-e10s', 06:14:25 INFO - '--xperf_path', 06:14:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:25 INFO - 'tests': ['tp5n']}, 06:14:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:14:25 INFO - 'talos_options': ['--xperf_path', 06:14:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:25 INFO - 'tests': ['tp5n']}}, 06:14:25 INFO - 'talos.zip': {'path': '', 06:14:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:25 INFO - Running post-action listener: _resource_record_post_action 06:14:25 INFO - [mozharness: 2016-11-21 14:14:25.693612Z] Finished populate-webroot step (success) 06:14:25 INFO - [mozharness: 2016-11-21 14:14:25.693747Z] Running create-virtualenv step. 06:14:25 INFO - Running pre-action listener: _resource_record_pre_action 06:14:25 INFO - Running main action method: create_virtualenv 06:14:25 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:25 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 06:14:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:25 INFO - Using real prefix '/tools/python27' 06:14:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:26 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:29 INFO - Installing pip.................done. 06:14:29 INFO - Return code: 0 06:14:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:29 INFO - Reading from file tmpfile_stdout 06:14:29 INFO - Output received: 06:14:29 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) 06:14:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:29 INFO - 'HOME': '/Users/cltbld', 06:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:29 INFO - 'LOGNAME': 'cltbld', 06:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:29 INFO - 'MOZ_NO_REMOTE': '1', 06:14:29 INFO - 'NO_EM_RESTART': '1', 06:14:29 INFO - 'PAGER': '/bin/cat', 06:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:29 INFO - 'PWD': '/builds/slave/test', 06:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:29 INFO - 'SHELL': '/bin/bash', 06:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:29 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:29 INFO - 'USER': 'cltbld', 06:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:29 INFO - 'XPC_FLAGS': '0x0', 06:14:29 INFO - 'XPC_SERVICE_NAME': '0', 06:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:29 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 06:14:29 INFO - Cleaning up... 06:14:29 INFO - Return code: 0 06:14:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:29 INFO - 'HOME': '/Users/cltbld', 06:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:29 INFO - 'LOGNAME': 'cltbld', 06:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:29 INFO - 'MOZ_NO_REMOTE': '1', 06:14:29 INFO - 'NO_EM_RESTART': '1', 06:14:29 INFO - 'PAGER': '/bin/cat', 06:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:29 INFO - 'PWD': '/builds/slave/test', 06:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:29 INFO - 'SHELL': '/bin/bash', 06:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:29 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:29 INFO - 'USER': 'cltbld', 06:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:29 INFO - 'XPC_FLAGS': '0x0', 06:14:29 INFO - 'XPC_SERVICE_NAME': '0', 06:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:30 INFO - Downloading/unpacking psutil>=3.1.1 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:33 INFO - Installing collected packages: psutil 06:14:33 INFO - Running setup.py install for psutil 06:14:33 INFO - building 'psutil._psutil_osx' extension 06:14:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:14:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:14:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:14:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:14:34 INFO - building 'psutil._psutil_posix' extension 06:14:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:14:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:14:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:14:34 INFO - ^ 06:14:34 INFO - 1 warning generated. 06:14:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:34 INFO - Successfully installed psutil 06:14:34 INFO - Cleaning up... 06:14:34 INFO - Return code: 0 06:14:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:34 INFO - 'HOME': '/Users/cltbld', 06:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:34 INFO - 'LOGNAME': 'cltbld', 06:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:34 INFO - 'MOZ_NO_REMOTE': '1', 06:14:34 INFO - 'NO_EM_RESTART': '1', 06:14:34 INFO - 'PAGER': '/bin/cat', 06:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:34 INFO - 'PWD': '/builds/slave/test', 06:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:34 INFO - 'SHELL': '/bin/bash', 06:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:34 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:34 INFO - 'USER': 'cltbld', 06:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:34 INFO - 'XPC_FLAGS': '0x0', 06:14:34 INFO - 'XPC_SERVICE_NAME': '0', 06:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:14:37 INFO - Installing collected packages: mozsystemmonitor 06:14:37 INFO - Running setup.py install for mozsystemmonitor 06:14:37 INFO - Successfully installed mozsystemmonitor 06:14:37 INFO - Cleaning up... 06:14:37 INFO - Return code: 0 06:14:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:37 INFO - 'HOME': '/Users/cltbld', 06:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:37 INFO - 'LOGNAME': 'cltbld', 06:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:37 INFO - 'MOZ_NO_REMOTE': '1', 06:14:37 INFO - 'NO_EM_RESTART': '1', 06:14:37 INFO - 'PAGER': '/bin/cat', 06:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:37 INFO - 'PWD': '/builds/slave/test', 06:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:37 INFO - 'SHELL': '/bin/bash', 06:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:37 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:37 INFO - 'USER': 'cltbld', 06:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:37 INFO - 'XPC_FLAGS': '0x0', 06:14:37 INFO - 'XPC_SERVICE_NAME': '0', 06:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:38 INFO - Downloading/unpacking jsonschema==2.5.1 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:14:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:40 INFO - Downloading functools32-3.2.3-2.tar.gz 06:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:14:40 INFO - warning: no files found matching '*.txt' 06:14:40 INFO - no previously-included directories found matching 'build' 06:14:40 INFO - no previously-included directories found matching 'dist' 06:14:40 INFO - no previously-included directories found matching '.git*' 06:14:41 INFO - Installing collected packages: jsonschema, functools32 06:14:41 INFO - Running setup.py install for functools32 06:14:41 INFO - warning: no files found matching '*.txt' 06:14:41 INFO - no previously-included directories found matching 'build' 06:14:41 INFO - no previously-included directories found matching 'dist' 06:14:41 INFO - no previously-included directories found matching '.git*' 06:14:41 INFO - Successfully installed jsonschema functools32 06:14:41 INFO - Cleaning up... 06:14:41 INFO - Return code: 0 06:14:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:41 INFO - 'HOME': '/Users/cltbld', 06:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:41 INFO - 'LOGNAME': 'cltbld', 06:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:41 INFO - 'MOZ_NO_REMOTE': '1', 06:14:41 INFO - 'NO_EM_RESTART': '1', 06:14:41 INFO - 'PAGER': '/bin/cat', 06:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:41 INFO - 'PWD': '/builds/slave/test', 06:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:41 INFO - 'SHELL': '/bin/bash', 06:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:41 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:41 INFO - 'USER': 'cltbld', 06:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:41 INFO - 'XPC_FLAGS': '0x0', 06:14:41 INFO - 'XPC_SERVICE_NAME': '0', 06:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:14:41 INFO - Cleaning up... 06:14:41 INFO - Return code: 0 06:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:41 INFO - 'HOME': '/Users/cltbld', 06:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:41 INFO - 'LOGNAME': 'cltbld', 06:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:41 INFO - 'MOZ_NO_REMOTE': '1', 06:14:41 INFO - 'NO_EM_RESTART': '1', 06:14:41 INFO - 'PAGER': '/bin/cat', 06:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:41 INFO - 'PWD': '/builds/slave/test', 06:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:41 INFO - 'SHELL': '/bin/bash', 06:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:41 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:41 INFO - 'USER': 'cltbld', 06:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:41 INFO - 'XPC_FLAGS': '0x0', 06:14:41 INFO - 'XPC_SERVICE_NAME': '0', 06:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:41 INFO - Downloading/unpacking blobuploader==1.2.4 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:44 INFO - Downloading blobuploader-1.2.4.tar.gz 06:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:14:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:14:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:14:45 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 06:14:45 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 06:14:45 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 06:14:45 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 06:14:45 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 06:14:45 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 06:14:45 INFO - Downloading docopt-0.6.1.tar.gz 06:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:14:45 INFO - Installing collected packages: blobuploader, requests, docopt 06:14:45 INFO - Running setup.py install for blobuploader 06:14:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:14:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:14:45 INFO - Running setup.py install for requests 06:14:46 INFO - Running setup.py install for docopt 06:14:46 INFO - Successfully installed blobuploader requests docopt 06:14:46 INFO - Cleaning up... 06:14:46 INFO - Return code: 0 06:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:46 INFO - 'HOME': '/Users/cltbld', 06:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:46 INFO - 'LOGNAME': 'cltbld', 06:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:46 INFO - 'MOZ_NO_REMOTE': '1', 06:14:46 INFO - 'NO_EM_RESTART': '1', 06:14:46 INFO - 'PAGER': '/bin/cat', 06:14:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:46 INFO - 'PWD': '/builds/slave/test', 06:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:46 INFO - 'SHELL': '/bin/bash', 06:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:46 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:46 INFO - 'USER': 'cltbld', 06:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:46 INFO - 'XPC_FLAGS': '0x0', 06:14:46 INFO - 'XPC_SERVICE_NAME': '0', 06:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:46 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-RwoMMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:46 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-wewuAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-za6aFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-bZdLCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-rEI1aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-orRzBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-TigTzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-zhZyCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pBE_Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-YDbFw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-8Lw1Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:47 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-PlWabH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:48 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-QASh7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:48 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ZzS5H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:48 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-wVIXLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:48 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-CDlCpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:48 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-gEfhqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:14:48 INFO - Running setup.py install for manifestparser 06:14:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:14:48 INFO - Running setup.py install for mozcrash 06:14:48 INFO - Running setup.py install for mozdebug 06:14:48 INFO - Running setup.py install for mozdevice 06:14:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Running setup.py install for mozfile 06:14:49 INFO - Running setup.py install for mozhttpd 06:14:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Running setup.py install for mozinfo 06:14:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Running setup.py install for mozInstall 06:14:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:14:49 INFO - Running setup.py install for mozleak 06:14:49 INFO - Running setup.py install for mozlog 06:14:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for moznetwork 06:14:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozprocess 06:14:50 INFO - Running setup.py install for mozprofile 06:14:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozrunner 06:14:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozscreenshot 06:14:50 INFO - Running setup.py install for moztest 06:14:50 INFO - Running setup.py install for mozversion 06:14:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:14:51 INFO - Cleaning up... 06:14:51 INFO - Return code: 0 06:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:51 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 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:51 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 06:14:51 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 06:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:51 INFO - 'HOME': '/Users/cltbld', 06:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:51 INFO - 'LOGNAME': 'cltbld', 06:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:51 INFO - 'MOZ_NO_REMOTE': '1', 06:14:51 INFO - 'NO_EM_RESTART': '1', 06:14:51 INFO - 'PAGER': '/bin/cat', 06:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:51 INFO - 'PWD': '/builds/slave/test', 06:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:51 INFO - 'SHELL': '/bin/bash', 06:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:51 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:51 INFO - 'USER': 'cltbld', 06:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:51 INFO - 'XPC_FLAGS': '0x0', 06:14:51 INFO - 'XPC_SERVICE_NAME': '0', 06:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-5xv_MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ZNH2f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:51 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)) 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-_dBUEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-6g9JNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-gTRQAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:51 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ac0dEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:52 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)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Y3gRYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:52 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)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-o30hvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:52 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)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-YrHCJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Ywu3H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:52 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)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-UScdzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-TiIUmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-qVZcNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-aGFTh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:52 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)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:52 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-gr926O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:53 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)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:53 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-lGGKJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:53 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-JNHVzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:53 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)) 06:14:53 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)) 06:14:53 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)) 06:14:53 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)) 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:55 INFO - Downloading blessings-1.6.tar.gz 06:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:14:55 INFO - Installing collected packages: blessings 06:14:55 INFO - Running setup.py install for blessings 06:14:56 INFO - Successfully installed blessings 06:14:56 INFO - Cleaning up... 06:14:56 INFO - Return code: 0 06:14:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:56 INFO - Reading from file tmpfile_stdout 06:14:56 INFO - Current package versions: 06:14:56 INFO - blessings == 1.6 06:14:56 INFO - blobuploader == 1.2.4 06:14:56 INFO - docopt == 0.6.1 06:14:56 INFO - functools32 == 3.2.3-2 06:14:56 INFO - jsonschema == 2.5.1 06:14:56 INFO - manifestparser == 1.1 06:14:56 INFO - mozInstall == 1.12 06:14:56 INFO - mozcrash == 1.0 06:14:56 INFO - mozdebug == 0.1 06:14:56 INFO - mozdevice == 0.49 06:14:56 INFO - mozfile == 1.2 06:14:56 INFO - mozhttpd == 0.7 06:14:56 INFO - mozinfo == 0.9 06:14:56 INFO - mozleak == 0.1 06:14:56 INFO - mozlog == 3.4 06:14:56 INFO - moznetwork == 0.27 06:14:56 INFO - mozprocess == 0.23 06:14:56 INFO - mozprofile == 0.28 06:14:56 INFO - mozrunner == 6.13 06:14:56 INFO - mozscreenshot == 0.1 06:14:56 INFO - mozsystemmonitor == 0.3 06:14:56 INFO - moztest == 0.7 06:14:56 INFO - mozversion == 1.4 06:14:56 INFO - psutil == 3.1.1 06:14:56 INFO - requests == 1.2.3 06:14:56 INFO - wsgiref == 0.1.2 06:14:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:56 INFO - 'HOME': '/Users/cltbld', 06:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:56 INFO - 'LOGNAME': 'cltbld', 06:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:56 INFO - 'MOZ_NO_REMOTE': '1', 06:14:56 INFO - 'NO_EM_RESTART': '1', 06:14:56 INFO - 'PAGER': '/bin/cat', 06:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:56 INFO - 'PWD': '/builds/slave/test', 06:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:56 INFO - 'SHELL': '/bin/bash', 06:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:56 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:56 INFO - 'USER': 'cltbld', 06:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:56 INFO - 'XPC_FLAGS': '0x0', 06:14:56 INFO - 'XPC_SERVICE_NAME': '0', 06:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:14:56 INFO - Cleaning up... 06:14:56 INFO - Return code: 0 06:14:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025d73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81d0cb8040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, '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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 06:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:56 INFO - 'HOME': '/Users/cltbld', 06:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:56 INFO - 'LOGNAME': 'cltbld', 06:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:56 INFO - 'MOZ_NO_REMOTE': '1', 06:14:56 INFO - 'NO_EM_RESTART': '1', 06:14:56 INFO - 'PAGER': '/bin/cat', 06:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:56 INFO - 'PWD': '/builds/slave/test', 06:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:56 INFO - 'SHELL': '/bin/bash', 06:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:14:56 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:56 INFO - 'USER': 'cltbld', 06:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:56 INFO - 'XPC_FLAGS': '0x0', 06:14:56 INFO - 'XPC_SERVICE_NAME': '0', 06:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:14:57 INFO - Cleaning up... 06:14:57 INFO - Return code: 0 06:14:57 INFO - Running post-action listener: _resource_record_post_action 06:14:57 INFO - Running post-action listener: _start_resource_monitoring 06:14:57 INFO - Starting resource monitoring. 06:14:57 INFO - [mozharness: 2016-11-21 14:14:57.051679Z] Finished create-virtualenv step (success) 06:14:57 INFO - [mozharness: 2016-11-21 14:14:57.052630Z] Running install step. 06:14:57 INFO - Running pre-action listener: _resource_record_pre_action 06:14:57 INFO - Running main action method: install 06:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:57 INFO - Reading from file tmpfile_stdout 06:14:57 INFO - Detecting whether we're running mozinstall >=1.0... 06:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:14:57 INFO - Reading from file tmpfile_stdout 06:14:57 INFO - Output received: 06:14:57 INFO - Usage: mozinstall [options] installer 06:14:57 INFO - Options: 06:14:57 INFO - -h, --help show this help message and exit 06:14:57 INFO - -d DEST, --destination=DEST 06:14:57 INFO - Directory to install application into. [default: 06:14:57 INFO - "/builds/slave/test"] 06:14:57 INFO - --app=APP Application being installed. [default: firefox] 06:14:57 INFO - mkdir: /builds/slave/test/build/application 06:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:15:29 INFO - Reading from file tmpfile_stdout 06:15:29 INFO - Output received: 06:15:29 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:15:29 INFO - Running post-action listener: _resource_record_post_action 06:15:29 INFO - [mozharness: 2016-11-21 14:15:29.723823Z] Finished install step (success) 06:15:29 INFO - [mozharness: 2016-11-21 14:15:29.723968Z] Running run-tests step. 06:15:29 INFO - Running pre-action listener: _resource_record_pre_action 06:15:29 INFO - Running main action method: run_tests 06:15:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:29 INFO - 2016-11-21 06:15:29.738 screenresolution[1623:7357] starting screenresolution argv=screenresolution get 06:15:29 INFO - 2016-11-21 06:15:29.741 screenresolution[1623:7357] Display 0: 1600x1200x32@60 06:15:29 INFO - 2016-11-21 06:15:29.747 screenresolution[1624:7359] starting screenresolution argv=screenresolution list 06:15:29 INFO - Available Modes on Display 0 06:15:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:29 INFO - Intel Iris: 06:15:29 INFO - Chipset Model: Intel Iris 06:15:29 INFO - Type: GPU 06:15:29 INFO - Bus: Built-In 06:15:29 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:29 INFO - Vendor: Intel (0x8086) 06:15:29 INFO - Device ID: 0x0a2e 06:15:29 INFO - Revision ID: 0x0009 06:15:29 INFO - Displays: 06:15:29 INFO - Display: 06:15:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:29 INFO - Display Serial Number: 1600x1200 60 06:15:29 INFO - Main Display: Yes 06:15:29 INFO - Mirror: Off 06:15:29 INFO - Online: Yes 06:15:29 INFO - Rotation: Supported 06:15:29 INFO - Return code: 0 06:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:29 INFO - Python 2.7.3 06:15:29 INFO - Return code: 0 06:15:29 INFO - grabbing minidump binary from tooltool 06:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023edd30>, '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 0x102254db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023e5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:15:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:15:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:15:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:30 INFO - Return code: 0 06:15:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:15:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:15:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:30 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 06:15:30 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0201 --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 06:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rlZxce04Ld/Render', 06:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:30 INFO - 'HOME': '/Users/cltbld', 06:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:30 INFO - 'LOGNAME': 'cltbld', 06:15:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:30 INFO - 'MOZ_NO_REMOTE': '1', 06:15:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:30 INFO - 'NO_EM_RESTART': '1', 06:15:30 INFO - 'PAGER': '/bin/cat', 06:15:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:30 INFO - 'PWD': '/builds/slave/test', 06:15:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:30 INFO - 'SHELL': '/bin/bash', 06:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HzS0RTM3Cp/Listeners', 06:15:30 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 06:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:30 INFO - 'USER': 'cltbld', 06:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:30 INFO - 'XPC_FLAGS': '0x0', 06:15:30 INFO - 'XPC_SERVICE_NAME': '0', 06:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:30 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 06:15:30 INFO - mozversion application_buildid: 20161121030224 06:15:30 INFO - mozversion application_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 06:15:30 INFO - mozversion application_display_name: Nightly 06:15:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:30 INFO - mozversion application_name: Firefox 06:15:30 INFO - mozversion application_remotingname: firefox 06:15:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:30 INFO - mozversion application_vendor: Mozilla 06:15:30 INFO - mozversion application_version: 53.0a1 06:15:30 INFO - mozversion platform_buildid: 20161121030224 06:15:30 INFO - mozversion platform_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 06:15:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:30 INFO - mozversion platform_version: 53.0a1 06:15:30 INFO - using testdate: 1479737730 06:15:30 INFO - actual date: 1479737730 06:15:30 INFO - starting webserver on 'localhost:49241' 06:15:30 INFO - SUITE-START | Running 2 tests 06:15:30 INFO - TEST-START | dromaeo_css 06:15:30 INFO - operating with platform_type : mac_ 06:15:30 INFO - Initialising browser for dromaeo_css test... 06:15:30 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpYAxli9/profile http://localhost:49241/getInfo.html) 06:15:33 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 06:15:33 INFO - PROCESS | 1634 | colorDepth:24 06:15:33 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 06:15:33 INFO - PROCESS | 1634 | __metrics 06:15:33 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:15:33 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:15:34 INFO - TEST-INFO | 1634: exit 0 06:15:34 INFO - Browser initialized. 06:15:34 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:34 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpYAxli9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:46 INFO - PROCESS | 1637 | RSS: Main: 236318720 06:15:46 INFO - PROCESS | 1637 | 06:16:37 INFO - PROCESS | 1637 | 1479737797189 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:18:50 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 06:18:52 INFO - PROCESS | 1637 | RSS: Main: 349622272 06:18:52 INFO - PROCESS | 1637 | 06:21:55 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 06:21:55 INFO - PROCESS | 1637 | RSS: Main: 380862464 06:21:55 INFO - PROCESS | 1637 | 06:24:58 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 06:24:59 INFO - PROCESS | 1637 | RSS: Main: 335507456 06:24:59 INFO - PROCESS | 1637 | 06:28:01 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 06:28:02 INFO - PROCESS | 1637 | RSS: Main: 332853248 06:28:02 INFO - PROCESS | 1637 | 06:31:05 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 06:31:06 INFO - PROCESS | 1637 | RSS: Main: 326533120 06:31:06 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 06:34:09 INFO - PROCESS | 1637 | RSS: Main: 446492672 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | __start_tp_report 06:34:09 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 06:34:09 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 06:34:09 INFO - PROCESS | 1637 | |i|pagename|runs| 06:34:09 INFO - PROCESS | 1637 | |0;dojo.html;3061.7703672224584;3278.22961604023;3315.1049216711504;3327.60068791745;3332.250243695168;5587.357453892803;5602.635828671134;5659.801906933253;5681.204631351604;5694.601377903548;5360.731963401834;5613.186088017235;5770.307563092435;5778;5811.273590801139;5095.719735414551;5103.004914041763;5154.175331946889;5163.81926632568;5163.993021360818;5242.55438287746;5271.894562108756;5290.709011004393;5295.232191332255;5297.761600727958;5260.158374660036;5264.368275806889;5295.4704529546925;5314.627976041678;5340.958513089949;5091.159958606825;5177.896442071137;5179.067767801795;5201.947980520222;5212.348456442945;5095.745212739348;5159.458256883011;5173.689578625257;5175.353080864892;5176.844694659167;4787.473377928425;5622.269105016363;5747.166660834156;5767.567432442584;5805.332386775491;6199.380061993809;6207.937920620826;6209.223847019122;6232.470240029557;6233.719482623293;5254.789808407659;6106.764041036368;6183.690815459254;6281.623102613858;6332.778352757631;5667.688277144755;5712.80005199816;5783.942160578425;5796.362400135981;5852.122181672783;6278.576338045009;6279.686015699288;6330.240371155399;6333.714982825784;6351.682415879188;6118.265808102967;6141.57009009374;6150.3849615039035;6155.076738489262;6162.907556386658;6092.482139018144;6116.405802871558;6118.019533855675;6144.354842741448;6206.441420272107;6180.752769889244;6197.194364732646;6197.5351848611535;6202.44178023971;6213.906791398133;6173.418011784561;6221.160143380676;6228.5017198624;6242.188515492957;6243.875122497525;4792.041591681608;4872.4883887192045;4884.413870335582;4889.828855990023;4926.778294976734;4381.298992161239;4406.603405693503;4408.294672852328;4437.267850804646;4477.977610111929;4902.8774280643265;5773.566982476331;5818.4763371296785;5848.61983971049;5849.210356601804;6100.853682747615;6133.8855759873895;6316.463100636405;6318;6326.778562750282;5625.043742563774;5667.319921609434;5670.347909990322;5759.136129580596;5786.88426231473;5269.473052694777;5310.840674779763;5314.4951229633125;5315.973420132875;5324.281222035053;6289.49684025286;6308.211473565804;6309.558330916883;6313.652749098752;6316.431521163117;5997.310309314485;6192.885280649526;6225.221847269091;6260.686965651608;6292.056191571118;6318.652474113968;6323.7786677466065;6324.146240257506;6335.113084168128;6351.587146835441;6140.692965351804;6203.4416902479;6209.223847019122;6212.192414986203;6219.440250377488;5340.279062326536;5717.313922329347;6171.660558669317;6182.969085154546;6196.628202307876;6094.9085763714465;6119.478862428838;6128.697664213171;6146.846328841814;6152.16945712341;6103.145559621746;6106.976420671822;6111.8777624446375;6112.755489780359;6121.908171377522;5786.652800831964;5855.502282305882;5900.616459930091;5951.145525664544;5963.314218864886;6059.969700151471;6126.609281623611;6127.678242056309;6139.676133582418;6156.969215153896;5976.372480889444;5977.790777322773;5986.521078313812;5990.850228744316;6054.364291749479 06:34:09 INFO - PROCESS | 1637 | |1;ext.html;469.5492327365729;489.96325275604295;509.98725031874187;511.2663328124142;520.0872468817223;29617.851910740443;39329.410058849106;40805.79597102014;40862;42083.78958105213;39480.21039579207;39805;39966.60033399672;39975.60024399755;40021.39967900477;9289.907100928971;9483.573239204217;9486.81026379472;9489.098535639137;9492;8464.195901389357;8668.219860212579;8676.652933882637;8789;8796.824063518727;11125.833112503318;11379.146564007691;11385;11544.53821847125;11550.653480395602;11241.1569132315;11288.943555282252;11300.208985371028;11324.886751132548;11328.150388720796;8324.29243514302;8549.444286121383;11139.944300278528;11198.272112312674;11216.775664486746;38855.80572097121;39145.825625231286;39317.803410982764;41043.973900652425;41290.38064429036;3316.817575033372;3317.950230746517;3321.8339083045753;3342.8300094966567;3416.009357286384;29313.85343073271;29500.11499655014;29681.554776678367;29873;30197.547036794465;3161.272907231327;3192.680731926812;3215.8070515769127;3220.388126256027;3298.670132986706;492724;495668;498467;499617;501461.49269253784;50334.24498632523;51171;57517.23517325107;58642.706786465795;58969.41030589639;60851.78296434046;61504.38495615076;61895.38104618896;61938.69030654818;62084.68957655273;15389.230538473032;15551.455699050479;15554.9222253888;15560.377584896703;15564.610884727746;11978.981786548242;12112.939435302767;12116.636500904988;12128.818067728991;12142.878571214174;14460.493882714054;14880.627984300263;14910.403583856742;15239.771403428957;16644.58388540272;5700.828975130753;6621.370969757865;6675.599464032173;6703.698333575001;6747;842.2966822703112;909.5179554835947;973.8539219117189;1053.8946105389552;1073.3988966178965;29105.41789164205;29344;29348.266293342836;30139.849300753795;30274.394512110073;1012.6810054832704;1057.0592172966067;1072.7264547540483;1099.230538622967;1113.2485572238868;14685.339159737838;14862.777058344134;15240.314185861664;15454.609085182388;15539.45611903578;624473;644097;644281;650638.746806263;650955;49494;50238;56254.71872640611;56493;57563.13655295174;1268.5433244032326;1301.7113058072441;1326.7280207557428;1330.0955350361846;1371.2389623758909;29638.259043524085;29954;30100.946466873553;30226.69773302239;30502.187226596674;50177.74911125421;51062.74468627633;51070.74464627653;51130.74434627952;51166.48833511766;483891.58054209506;635580;641373.7931310313;657062;830572;24491.63262551099;24574.385640359134;24781.752182477943;25004.749952500242;25021.249362519153;44272.33591496194;45247.32129018133;46142.53857461383;46217;48509.27236091532;29335.973240936466;35753;36112.29157813122;38874.22251555009;39252.60747392604;36916.446253306756;37400.06499837526;37850.6252112201;38556.614433856426;38714.80642596882;37357.25285494329;37364.813175933945;37655.81172094122;38407.42388864113;38510.45958161642;30867.845660771553;32585.34829303448;33635.66364336325;33674.49488257629;33832.98501044972;21699.240526581492;23868.045278188678;24613.87693061523;24737.628935565623;24771.752282476944 06:34:09 INFO - PROCESS | 1637 | |2;jquery.html;346.7035684489763;367.2929610499789;368.82112175594835;375.86281007432285;397.7235821104332;14316.642083947894;14595;15396.769048464283;19193.904030479862;19890.602187956232;18482.44552663419;19676.901615491937;19769.406917792454;19830.70253946192;19980.700289495668;5733.140028995653;6106.786262480814;6107.297660769024;6112.205413296265;6122.510199184077;5210.791568337262;5848.590598658096;5992.34084250732;6039.879202415949;6055.303640081403;5322.494363035525;5711.086226203808;5795.101759227306;5991.310999235078;5994.760209591611;5750.7412166452405;5892.351841297454;5900;5903.203067585864;5906.409359064102;4889.364382630235;5079.796808127708;5144.922826157611;5147.459516750762;5157.896842063175;20079.598408031903;24466.755332446803;24493.38766530833;24789.380265493506;24934.251972440674;2023.5042414608515;2217.81148602369;2220.9888950555305;2236.105557776886;2445.3886528367907;9839.655612053544;10453.268271221017;13178.143420677625;14046.367913443817;15371.461998830098;1771.3003363671278;1789.6062866169423;1796.5418818201274;1867.5517875709868;1931.4881556387568;113677;121288.0840724339;121505;121579.15523093934;121860;9214.677486287948;10889.782204355868;11157.553697852158;11216.345216735126;11417.143714221467;14086.366113524917;14140.717185656229;14142.36359363831;14164.858351416355;14410.567682969318;7950.880736788837;8232.794180145544;8247.340212782963;8352.582370881431;8398.958005210057;8044.839103217902;8066.959665201636;8068.152843951418;8224.259816616413;8232.958835205785;4393.780310984438;4403.776093073042;4410.779461026936;4433.556644335541;4512.503624601291;1560.4538411555864;1880.3982725527974;1883.7174423836534;1912.7704675438758;1922.875327592662;805.3396215103676;881.3566096749408;911.5533388639651;940.3182692547985;1029.505837198134;20226.59546809085;20884.37346879596;20945.685814712488;21006.2647807326;21094.57810843805;895.6372669069037;902.9006809250977;927.5547737086237;933.570557543524;968.2254196642658;10455.84316235257;10515.369077855354;10875.564977400974;10917.126629869592;10938.234323597431;560724;562158;563663;570980;573543;92849;98670.50664746774;111288;111657;120635;866.8959724833061;870.9608067636972;933.9813203735887;935.8876934767871;937.6483818568037;17177.31290748406;19032.904835475732;19037.333693320666;19047.52381190426;19131.426057218305;46477.53522464867;49064.2640360402;52334.73832630813;52415.47584524258;53659.73170134125;72983.90524142036;75669.48661026859;75959.62020189864;77684.61157694175;78491.60754196193;6710.530262881552;6870.690818913061;7082.433405327664;7125.394341481032;7273.490855640054;10340.844887326846;11995.580154694893;12015.399230038638;12289.877101228873;12377.443015064524;10658.573657053632;10703.090237329914;10733.570657173626;10883.401412922365;10924.180686448708;9403.106704863027;10174.186065114922;10417.16662667;10748.140148788234;10838.620648277272;9312.255019598551;10270.794584108426;10317.587296508056;10325.32885362449;10388.428636424767;7009.544379615309;7700.730474433368;7767.6116194188935;7938.5236885784725;8138.226868447488;10584.735381615521;10611.840822387825;10783.245172837826;10858.34849909008;11193.944030280123 06:34:09 INFO - PROCESS | 1637 | |3;mootools.html;1495.4317359403422;1503.9172845493497;1588.340838552001;1677.865770738341;1769.1950162675985;7985.48094373866;8431.536265505394;8447.619857106429;8453.112423195567;8453.450525715823;7389.741359052434;7586.279303466162;8031.196880311966;8159.388045896551;8170.714025009126;2287.691161693171;2321.8374713770045;2326.8603883766946;2327.3483424641126;2328.1036800831635;2307.6077066898583;2336.8714720690355;2352.2590384028977;2355.7644235576477;2358.127492827656;2020.191923230712;2089.9059542320633;2092.79072092791;2222.155580879272;2298.310506847939;2281.6577513373045;2296.8966396512196;2297.1041293895396;2300.7354154272307;2302.2172461363216;2285.5029955379255;2321.013569233086;2322.953540929189;2328.382978510696;2332.9066837326486;8224.917750822475;8298.21166989135;8300.70947516834;8312.085670576233;8340.040895296997;5015.222640490693;5148.639595228336;5176.922346164811;5179.81870634526;5200.557952574036;3284.490903909898;3335.1328654549748;3507.8947631571095;3577.266660334651;3579.6778289954027;1055.7782865598233;1079.5034284229264;1080.1304949515636;1100.0979197058416;1101.9008289253925;10771.24601277911;10848.945755271252;10955.726106847313;10960.232783705143;10971.561137554489;4427.203103441347;4427.667924905645;4642.675012749075;4652.116097941448;4669.392978912788;9364.859527107099;9381.202597779265;9386.530673466299;9387.906120938704;9389.483578403253;5126.026055049529;5138.97430512845;5139.486051394831;5152.4847515248175;5165.354330708661;4634.119517291704;4651.348811166439;4706.435227772621;4708.788104535236;4710.552497512731;3255.8209298488337;3650.178709790276;3731.8134093295225;3754;3784.7539909905768;1234.851817781854;1237.1649136832602;1237.412229191127;1247.2454165230088;1262.9684257893625;602.4607975861579;669.718718138383;853.1894700034992;923.2475532441173;929.9628014879329;4780.665353425227;5447.237386765856;5726.885462290814;5762.798302059407;5900.20347253124;783.561205724796;846.4667259626395;885.9069797671152;918.875951746519;961.7595601099725;3346.330733853239;3835.7506762060375;3839.2897313997;3939.290927632996;3948.9012774680864;9837.311388202757;10042.246831487668;10163.898361016443;10179.949100254451;10219;10126.54430550627;10128.949355253177;10133.594656213816;10139.898601014043;10143.898561014295;7396.741114060982;7674.923250767532;7776.65005074773;7832.099308579473;7839.137694853562;5698.8290351289525;5885.882282354414;5897.7935772247765;5922.378150294157;5940.61386009908;9026.819463610822;9354.017828127948;9460.2904782143;9481.478518681539;9487.905120948979;9556.331056825955;9574.808503830023;9582.233421326138;9656.420614763138;9708.85436718464;6994.650267486503;7079.646017699198;7128.037714908421;7147.142342918883;7151.499395042297;7466.589337586485;7631.771046868603;7659.731909383145;7661.272179142972;7679.308862202429;6800.52396332252;6884.896726549206;6943.618101004493;6963.40811031068;7053.118360204974;6780;6853;6863.347981941708;6921.169459664872;6944.826379340355;6402.6158430494315;6474.1907261592305;6486.286508484157;6553.836154096186;6566.80299591013;4689.249720044776;4780.498047704941;4813.24757950234;4835.854924352275;4957.702537847741;4831.396075490564;4889.095517329305;4922.3082687984015;4962.5533701967;4994.225894986283 06:34:09 INFO - PROCESS | 1637 | |4;prototype.html;297.6458014962195;304.69987062743206;308.25248771728565;312.5967501922519;313.40609544912405;4892.87767805805;5102.540771330579;5103.004914041759;5129.435762066169;5151.253068305094;4078.143589846136;4274.935875961855;4872.731999740013;4989;5121.078205922932;3544.5923718772415;3638.7089032877307;3711.220643664827;3869.613038696136;3937.7243592948507;3308.5111699735094;3891.20230352777;3928.2536318099615;3953.4069889516513;3955.92314035131;3868.477755503013;3890.7471014383973;3895.3377925752548;3896.5129358830145;3905.902352441183;3458.5330980317585;3693.4644476550884;3753.2868754936476;3830.448668289347;3892.4355968384566;3131.8590663419973;3403.9659603403898;3407.1993081625797;3408.244753951717;3420.401429749784;4888.266759985995;4907.1657818171;4932.926006109911;4939.777710003059;4941.827036053721;3573.9456860226296;3697.1699008990454;3977.5028121484816;4132.483439570054;4391.780410979471;5283.339582552181;5285.762140703678;5290.444503327172;5328.866778330573;5329.067413202674;3724.608916063801;3727.8192257529377;3730.1793605406765;3744.588095309514;3747.9063023424633;5142.820001299937;5479.123340265578;5508.283923090013;5520.1443776214755;5527.585431092644;3326.800391976489;3426.989038233727;3521.6126226115107;3670.5317872850537;3734.589195188527;3776.546814382292;3777.338965680995;3803.9568345323632;3809.6190380962234;3810.028442747082;4381.474223093249;4388.232059389594;4392.824287028547;4394.572233657752;4422.447194100738;4709.62323014158;4783.904321913542;4935.432425271068;5170.45710200431;5403.352357344873;1229.692576855786;1238.2446707508414;1239.2750241108872;1242.912996090283;1252.2361359570655;780.0989856715503;834.7871292820292;852.5055467828645;854.2354592639552;857.8970523537091;4430.9869288883865;4768.594669453136;4893.437254715754;5015.423226329019;5024.57291130258;4327.675424343187;5257.7896884124975;5475.178723191473;5489.33767122945;5557.249771280906;4250.55369186231;4810.725157833177;5012.8496145115705;5014.247862820606;5067.949320506821;2389.7610238975963;2473.9752602473745;2482.5973325071277;2516.993202718927;2631.9868400658256;4945.554900059012;5041.193409054534;5242.475752424803;5480.657238976397;5504.4770746778995;4723.95276047242;4746.477887432379;4793.832215872428;4977;5280.762365693553;4841.8547443576745;5012.7994880205115;5166.767495462638;5370.892582148414;5441.265429167091;5199.766010529611;5247.1866860636665;5315.9468405317;5323.733813309396;5328.25404443386;5072.087024335655;5141.717205553581;5162.793488260428;5181.5595674368105;5256.6320357574605;5764.654120752768;5765.798197063083;5781.710914454176;5782.479576838105;5808.593398462067;3787.3182827091387;3789.621037896188;3792.544894612664;3799.4680744696316;3810.218905124498;5234;5270.446603106619;5280.735963201748;5536.944630553804;5586.13414920688;4723.661787415252;5322.148456246982;5422.319081084876;5422.5119739223655;5432.59255555827;4577.793999269974;4823.684631376888;4955.5044495550155;5027.59227416324;5379.892402152013;5024.723640199824;5130.999455106218;5281.313429254173;5296.549793267615;5305.814296499604;5149;5306.814261500829;5389.353277606712;5425.294711687613;5427.918581221206;4057.695672824609;5197.324347834758;5306.593752655473;5308.362996440452;5316.521513063843 06:34:09 INFO - PROCESS | 1637 | |5;yui.html;666.8066260784373;697.4420463629097;738.7016969232376;749.6926260233305;793.3296364571936;14210.786838197422;14945.551633450987;14955.626109347259;14982.700345993073;15005.62485937852;13326.267055311953;13524.459021639148;13562.118462299968;13567.864321356808;13629.25039122848;666.4468491152332;688.6315821035747;693.012457248419;824.3281725393784;833.9374546908975;837.5519097282955;841.4446465332882;852.5992783391796;852.6504133305347;859.4070091636781;884.8672699095123;888.7200531832484;889.1863944490816;892.1346294094718;892.2460520859863;690;715.7995761186876;881.7707396077002;882.7660669922476;885.4775682347381;542.6391449685954;544.5235419008368;545.0624925128773;546.519063224364;650.391883588846;13856.30718464083;13871.79192312106;13888.722225555533;13897;13926.58220253394;12709.809352859622;12826.422810973529;12895.355232238444;13055.673608159777;13195.670108247275;11899.226550274205;11964.102692298113;12032.759344813056;12053.276803391824;12193.329366884818;4310.418093557361;4338.175746608134;4343;4511.729296242236;4532.229520981408;85401;87628;88708;88713.22573548493;89211.55394223052;8613.310935125175;10356.430396328124;10447.895521044693;10477.37135771856;10768.14644119309;19144.61710765777;19257.711134332996;19401.902990484956;19443.708344374845;19759.60480790376;7360.78589290419;7393.889091663629;7415.85168296631;7486.625668716542;7517.548947063194;4151.6886233532605;4225.936610950838;4245.936310955338;4254.383114448449;4343.891402714894;3133.341998180362;3213.4697774867354;3566.4828599852876;3725.776453412804;3726.0312318796764;668.2348710726288;675.5237557521936;680.8195828105556;682.0417313674295;689.2280645676774;490.35763150273254;584.4564554963839;649.2086216495594;654.0777503719733;660.600336796241;8652.091530389345;8678.522681252465;8745.60644770974;8835.33734969867;8854.317390306449;544.1782907809157;546.4398991034142;639.2935805934468;644.6067898581862;664.226176504367;4053.9797301013896;4236.957630423718;4369.672274579356;4404.141192467502;4447.177272204653;84109;104297.77091546923;105988;110925.44537277262;111260.94144866492;95012.57481137788;97047;97848;98800;99041.50479247558;65556.01665975014;83290.16709832824;85144;85781;89539.55230223938;8434.451760635538;8584.957075214585;8590.484570925764;8599.355048371272;8672.479651220947;46578;47472.7626361866;55427.72286138544;56257;57297.85404291807;66738;67423.66288168528;68038;68915.65542172457;69960.65019674868;11831.290122592327;12038.277703337828;12162.270263784201;12310.81533777012;12844.35778211067;13099.41052652652;13495.25776082325;13838.993345964578;14406.351714173097;14460;12569.308688021882;12770.361481926053;13025.478980841039;13226.206427614374;13615.523456679364;12013.75972480563;12574.182678125893;12625.621231363073;12724.809127863275;13238.735225295248;12439.626811195678;12796;12820.038497112568;13120.475180993035;13207.735845283232;10321.709786276715;11136;11241.38172400493;11366.033887119687;11496.54013839437;10506.842397363893;10722.287209786566;11012.614558490415;11085;11138.10895128404 06:34:09 INFO - PROCESS | 1637 | __end_tp_report 06:34:09 INFO - PROCESS | 1637 | __start_cc_report 06:34:09 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1275 06:34:09 INFO - PROCESS | 1637 | __end_cc_report 06:34:09 INFO - PROCESS | 1637 | __startTimestamp1479738849584__endTimestamp 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | ------- Summary: start ------- 06:34:09 INFO - PROCESS | 1637 | Number of tests: 6 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5714.26 Median:5963.31 stddev:658.79 (11.0%) stddev-sans-first:627.08 06:34:09 INFO - PROCESS | 1637 | Values: 3061.8 3278.2 3315.1 3327.6 3332.3 5587.4 5602.6 5659.8 5681.2 5694.6 5360.7 5613.2 5770.3 5778.0 5811.3 5095.7 5103.0 5154.2 5163.8 5164.0 5242.6 5271.9 5290.7 5295.2 5297.8 5260.2 5264.4 5295.5 5314.6 5341.0 5091.2 5177.9 5179.1 5201.9 5212.3 5095.7 5159.5 5173.7 5175.4 5176.8 4787.5 5622.3 5747.2 5767.6 5805.3 6199.4 6207.9 6209.2 6232.5 6233.7 5254.8 6106.8 6183.7 6281.6 6332.8 5667.7 5712.8 5783.9 5796.4 5852.1 6278.6 6279.7 6330.2 6333.7 6351.7 6118.3 6141.6 6150.4 6155.1 6162.9 6092.5 6116.4 6118.0 6144.4 6206.4 6180.8 6197.2 6197.5 6202.4 6213.9 6173.4 6221.2 6228.5 6242.2 6243.9 4792.0 4872.5 4884.4 4889.8 4926.8 4381.3 4406.6 4408.3 4437.3 4478.0 4902.9 5773.6 5818.5 5848.6 5849.2 6100.9 6133.9 6316.5 6318.0 6326.8 5625.0 5667.3 5670.3 5759.1 5786.9 5269.5 5310.8 5314.5 5316.0 5324.3 6289.5 6308.2 6309.6 6313.7 6316.4 5997.3 6192.9 6225.2 6260.7 6292.1 6318.7 6323.8 6324.1 6335.1 6351.6 6140.7 6203.4 6209.2 6212.2 6219.4 5340.3 5717.3 6171.7 6183.0 6196.6 6094.9 6119.5 6128.7 6146.8 6152.2 6103.1 6107.0 6111.9 6112.8 6121.9 5786.7 5855.5 5900.6 5951.1 5963.3 6060.0 6126.6 6127.7 6139.7 6157.0 5976.4 5977.8 5986.5 5990.9 6054.4 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:71805.27 Median:37361.03 stddev:161985.11 (433.6%) stddev-sans-first:162350.97 06:34:09 INFO - PROCESS | 1637 | Values: 469.5 490.0 510.0 511.3 520.1 29617.9 39329.4 40805.8 40862.0 42083.8 39480.2 39805.0 39966.6 39975.6 40021.4 9289.9 9483.6 9486.8 9489.1 9492.0 8464.2 8668.2 8676.7 8789.0 8796.8 11125.8 11379.1 11385.0 11544.5 11550.7 11241.2 11288.9 11300.2 11324.9 11328.2 8324.3 8549.4 11139.9 11198.3 11216.8 38855.8 39145.8 39317.8 41044.0 41290.4 3316.8 3318.0 3321.8 3342.8 3416.0 29313.9 29500.1 29681.6 29873.0 30197.5 3161.3 3192.7 3215.8 3220.4 3298.7 492724.0 495668.0 498467.0 499617.0 501461.5 50334.2 51171.0 57517.2 58642.7 58969.4 60851.8 61504.4 61895.4 61938.7 62084.7 15389.2 15551.5 15554.9 15560.4 15564.6 11979.0 12112.9 12116.6 12128.8 12142.9 14460.5 14880.6 14910.4 15239.8 16644.6 5700.8 6621.4 6675.6 6703.7 6747.0 842.3 909.5 973.9 1053.9 1073.4 29105.4 29344.0 29348.3 30139.8 30274.4 1012.7 1057.1 1072.7 1099.2 1113.2 14685.3 14862.8 15240.3 15454.6 15539.5 624473.0 644097.0 644281.0 650638.7 650955.0 49494.0 50238.0 56254.7 56493.0 57563.1 1268.5 1301.7 1326.7 1330.1 1371.2 29638.3 29954.0 30100.9 30226.7 30502.2 50177.7 51062.7 51070.7 51130.7 51166.5 483891.6 635580.0 641373.8 657062.0 830572.0 24491.6 24574.4 24781.8 25004.7 25021.2 44272.3 45247.3 46142.5 46217.0 48509.3 29336.0 35753.0 36112.3 38874.2 39252.6 36916.4 37400.1 37850.6 38556.6 38714.8 37357.3 37364.8 37655.8 38407.4 38510.5 30867.8 32585.3 33635.7 33674.5 33833.0 21699.2 23868.0 24613.9 24737.6 24771.8 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:33191.36 Median:357.00 stddev:94388.51 (26439.5%) stddev-sans-first:94621.08 06:34:09 INFO - PROCESS | 1637 | Values: 346.7 367.3 368.8 375.9 397.7 14316.6 14595.0 15396.8 19193.9 19890.6 18482.4 19676.9 19769.4 19830.7 19980.7 5733.1 6106.8 6107.3 6112.2 6122.5 5210.8 5848.6 5992.3 6039.9 6055.3 5322.5 5711.1 5795.1 5991.3 5994.8 5750.7 5892.4 5900.0 5903.2 5906.4 4889.4 5079.8 5144.9 5147.5 5157.9 20079.6 24466.8 24493.4 24789.4 24934.3 2023.5 2217.8 2221.0 2236.1 2445.4 9839.7 10453.3 13178.1 14046.4 15371.5 1771.3 1789.6 1796.5 1867.6 1931.5 113677.0 121288.1 121505.0 121579.2 121860.0 9214.7 10889.8 11157.6 11216.3 11417.1 14086.4 14140.7 14142.4 14164.9 14410.6 7950.9 8232.8 8247.3 8352.6 8399.0 8044.8 8067.0 8068.2 8224.3 8233.0 4393.8 4403.8 4410.8 4433.6 4512.5 1560.5 1880.4 1883.7 1912.8 1922.9 805.3 881.4 911.6 940.3 1029.5 20226.6 20884.4 20945.7 21006.3 21094.6 895.6 902.9 927.6 933.6 968.2 10455.8 10515.4 10875.6 10917.1 10938.2 560724.0 562158.0 563663.0 570980.0 573543.0 92849.0 98670.5 111288.0 111657.0 120635.0 866.9 871.0 934.0 935.9 937.6 17177.3 19032.9 19037.3 19047.5 19131.4 46477.5 49064.3 52334.7 52415.5 53659.7 72983.9 75669.5 75959.6 77684.6 78491.6 6710.5 6870.7 7082.4 7125.4 7273.5 10340.8 11995.6 12015.4 12289.9 12377.4 10658.6 10703.1 10733.6 10883.4 10924.2 9403.1 10174.2 10417.2 10748.1 10838.6 9312.3 10270.8 10317.6 10325.3 10388.4 7009.5 7700.7 7767.6 7938.5 8138.2 10584.7 10611.8 10783.2 10858.3 11193.9 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5424.69 Median:5070.62 stddev:2983.95 (58.8%) stddev-sans-first:2977.71 06:34:09 INFO - PROCESS | 1637 | Values: 1495.4 1503.9 1588.3 1677.9 1769.2 7985.5 8431.5 8447.6 8453.1 8453.5 7389.7 7586.3 8031.2 8159.4 8170.7 2287.7 2321.8 2326.9 2327.3 2328.1 2307.6 2336.9 2352.3 2355.8 2358.1 2020.2 2089.9 2092.8 2222.2 2298.3 2281.7 2296.9 2297.1 2300.7 2302.2 2285.5 2321.0 2323.0 2328.4 2332.9 8224.9 8298.2 8300.7 8312.1 8340.0 5015.2 5148.6 5176.9 5179.8 5200.6 3284.5 3335.1 3507.9 3577.3 3579.7 1055.8 1079.5 1080.1 1100.1 1101.9 10771.2 10848.9 10955.7 10960.2 10971.6 4427.2 4427.7 4642.7 4652.1 4669.4 9364.9 9381.2 9386.5 9387.9 9389.5 5126.0 5139.0 5139.5 5152.5 5165.4 4634.1 4651.3 4706.4 4708.8 4710.6 3255.8 3650.2 3731.8 3754.0 3784.8 1234.9 1237.2 1237.4 1247.2 1263.0 602.5 669.7 853.2 923.2 930.0 4780.7 5447.2 5726.9 5762.8 5900.2 783.6 846.5 885.9 918.9 961.8 3346.3 3835.8 3839.3 3939.3 3948.9 9837.3 10042.2 10163.9 10179.9 10219.0 10126.5 10128.9 10133.6 10139.9 10143.9 7396.7 7674.9 7776.7 7832.1 7839.1 5698.8 5885.9 5897.8 5922.4 5940.6 9026.8 9354.0 9460.3 9481.5 9487.9 9556.3 9574.8 9582.2 9656.4 9708.9 6994.7 7079.6 7128.0 7147.1 7151.5 7466.6 7631.8 7659.7 7661.3 7679.3 6800.5 6884.9 6943.6 6963.4 7053.1 6780.0 6853.0 6863.3 6921.2 6944.8 6402.6 6474.2 6486.3 6553.8 6566.8 4689.2 4780.5 4813.2 4835.9 4957.7 4831.4 4889.1 4922.3 4962.6 4994.2 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4295.10 Median:4900.30 stddev:1312.48 (26.8%) stddev-sans-first:1281.41 06:34:09 INFO - PROCESS | 1637 | Values: 297.6 304.7 308.3 312.6 313.4 4892.9 5102.5 5103.0 5129.4 5151.3 4078.1 4274.9 4872.7 4989.0 5121.1 3544.6 3638.7 3711.2 3869.6 3937.7 3308.5 3891.2 3928.3 3953.4 3955.9 3868.5 3890.7 3895.3 3896.5 3905.9 3458.5 3693.5 3753.3 3830.4 3892.4 3131.9 3404.0 3407.2 3408.2 3420.4 4888.3 4907.2 4932.9 4939.8 4941.8 3573.9 3697.2 3977.5 4132.5 4391.8 5283.3 5285.8 5290.4 5328.9 5329.1 3724.6 3727.8 3730.2 3744.6 3747.9 5142.8 5479.1 5508.3 5520.1 5527.6 3326.8 3427.0 3521.6 3670.5 3734.6 3776.5 3777.3 3804.0 3809.6 3810.0 4381.5 4388.2 4392.8 4394.6 4422.4 4709.6 4783.9 4935.4 5170.5 5403.4 1229.7 1238.2 1239.3 1242.9 1252.2 780.1 834.8 852.5 854.2 857.9 4431.0 4768.6 4893.4 5015.4 5024.6 4327.7 5257.8 5475.2 5489.3 5557.2 4250.6 4810.7 5012.8 5014.2 5067.9 2389.8 2474.0 2482.6 2517.0 2632.0 4945.6 5041.2 5242.5 5480.7 5504.5 4724.0 4746.5 4793.8 4977.0 5280.8 4841.9 5012.8 5166.8 5370.9 5441.3 5199.8 5247.2 5315.9 5323.7 5328.3 5072.1 5141.7 5162.8 5181.6 5256.6 5764.7 5765.8 5781.7 5782.5 5808.6 3787.3 3789.6 3792.5 3799.5 3810.2 5234.0 5270.4 5280.7 5536.9 5586.1 4723.7 5322.1 5422.3 5422.5 5432.6 4577.8 4823.7 4955.5 5027.6 5379.9 5024.7 5131.0 5281.3 5296.5 5305.8 5149.0 5306.8 5389.4 5425.3 5427.9 4057.7 5197.3 5306.6 5308.4 5316.5 06:34:09 INFO - PROCESS | 1637 | 06:34:09 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20070.55 Median:4479.45 stddev:29181.36 (651.4%) stddev-sans-first:29226.85 06:34:09 INFO - PROCESS | 1637 | Values: 666.8 697.4 738.7 749.7 793.3 14210.8 14945.6 14955.6 14982.7 15005.6 13326.3 13524.5 13562.1 13567.9 13629.3 666.4 688.6 693.0 824.3 833.9 837.6 841.4 852.6 852.7 859.4 884.9 888.7 889.2 892.1 892.2 690.0 715.8 881.8 882.8 885.5 542.6 544.5 545.1 546.5 650.4 13856.3 13871.8 13888.7 13897.0 13926.6 12709.8 12826.4 12895.4 13055.7 13195.7 11899.2 11964.1 12032.8 12053.3 12193.3 4310.4 4338.2 4343.0 4511.7 4532.2 85401.0 87628.0 88708.0 88713.2 89211.6 8613.3 10356.4 10447.9 10477.4 10768.1 19144.6 19257.7 19401.9 19443.7 19759.6 7360.8 7393.9 7415.9 7486.6 7517.5 4151.7 4225.9 4245.9 4254.4 4343.9 3133.3 3213.5 3566.5 3725.8 3726.0 668.2 675.5 680.8 682.0 689.2 490.4 584.5 649.2 654.1 660.6 8652.1 8678.5 8745.6 8835.3 8854.3 544.2 546.4 639.3 644.6 664.2 4054.0 4237.0 4369.7 4404.1 4447.2 84109.0 104297.8 105988.0 110925.4 111260.9 95012.6 97047.0 97848.0 98800.0 99041.5 65556.0 83290.2 85144.0 85781.0 89539.6 8434.5 8585.0 8590.5 8599.4 8672.5 46578.0 47472.8 55427.7 56257.0 57297.9 66738.0 67423.7 68038.0 68915.7 69960.7 11831.3 12038.3 12162.3 12310.8 12844.4 13099.4 13495.3 13839.0 14406.4 14460.0 12569.3 12770.4 13025.5 13226.2 13615.5 12013.8 12574.2 12625.6 12724.8 13238.7 12439.6 12796.0 12820.0 13120.5 13207.7 10321.7 11136.0 11241.4 11366.0 11496.5 10506.8 10722.3 11012.6 11085.0 11138.1 06:34:09 INFO - PROCESS | 1637 | -------- Summary: end -------- 06:34:09 INFO - PROCESS | 1637 | 06:34:10 INFO - TEST-INFO | 1637: exit 0 06:34:10 INFO - TEST-OK | dromaeo_css | took 1119958ms 06:34:10 INFO - TEST-START | kraken 06:34:10 INFO - operating with platform_type : mac_ 06:34:10 INFO - Initialising browser for kraken test... 06:34:10 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmp_8uhet/profile http://localhost:49241/getInfo.html) 06:34:13 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 06:34:13 INFO - PROCESS | 1647 | colorDepth:24 06:34:13 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 06:34:13 INFO - PROCESS | 1647 | __metrics 06:34:13 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:34:13 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:34:14 INFO - TEST-INFO | 1647: exit 0 06:34:14 INFO - Browser initialized. 06:34:14 INFO - Running cycle 1/1 for kraken test... 06:34:14 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmp_8uhet/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:26 INFO - PROCESS | 1649 | RSS: Main: 244064256 06:34:26 INFO - PROCESS | 1649 | 06:34:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:30 INFO - PROCESS | 1649 | RSS: Main: 296202240 06:34:30 INFO - PROCESS | 1649 | 06:34:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 06:34:33 INFO - PROCESS | 1649 | RSS: Main: 297558016 06:34:33 INFO - PROCESS | 1649 | 06:34:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 06:34:37 INFO - PROCESS | 1649 | RSS: Main: 387923968 06:34:37 INFO - PROCESS | 1649 | 06:34:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 06:34:40 INFO - PROCESS | 1649 | RSS: Main: 292192256 06:34:40 INFO - PROCESS | 1649 | 06:34:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:34:43 INFO - PROCESS | 1649 | RSS: Main: 294301696 06:34:43 INFO - PROCESS | 1649 | 06:34:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 06:34:46 INFO - PROCESS | 1649 | RSS: Main: 334888960 06:34:46 INFO - PROCESS | 1649 | 06:34:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 06:34:49 INFO - PROCESS | 1649 | RSS: Main: 338001920 06:34:49 INFO - PROCESS | 1649 | 06:34:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 06:34:53 INFO - PROCESS | 1649 | RSS: Main: 334626816 06:34:53 INFO - PROCESS | 1649 | 06:34:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:34:55 INFO - PROCESS | 1649 | RSS: Main: 310710272 06:34:55 INFO - PROCESS | 1649 | 06:34:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:34:58 INFO - PROCESS | 1649 | RSS: Main: 315768832 06:34:58 INFO - PROCESS | 1649 | 06:35:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:01 INFO - PROCESS | 1649 | RSS: Main: 317988864 06:35:01 INFO - PROCESS | 1649 | 06:35:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:04 INFO - PROCESS | 1649 | RSS: Main: 333402112 06:35:04 INFO - PROCESS | 1649 | 06:35:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:08 INFO - PROCESS | 1649 | RSS: Main: 326131712 06:35:08 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 06:35:10 INFO - PROCESS | 1649 | RSS: Main: 369426432 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | __start_tp_report 06:35:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 06:35:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 06:35:10 INFO - PROCESS | 1649 | |i|pagename|runs| 06:35:10 INFO - PROCESS | 1649 | |0;ai-astar;113;115;114;114;113;167;163;113;114;114 06:35:10 INFO - PROCESS | 1649 | |1;audio-beat-detection;151;114;117;177;137;124;116;112;141;112 06:35:10 INFO - PROCESS | 1649 | |2;audio-dft;188;182;185;187;182;182;183;182;181;185 06:35:10 INFO - PROCESS | 1649 | |3;audio-fft;79;81;80;109;60;58;61;78;107;78 06:35:10 INFO - PROCESS | 1649 | |4;audio-oscillator;91;71;96;70;69;68;90;68;68;93 06:35:10 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;87;84;86;84;87;84;88;86;86;84 06:35:10 INFO - PROCESS | 1649 | |6;imaging-darkroom;93;96;94;95;95;95;95;94;95;94 06:35:10 INFO - PROCESS | 1649 | |7;imaging-desaturate;88;84;87;89;87;87;87;87;87;92 06:35:10 INFO - PROCESS | 1649 | |8;json-parse-financial;52;52;52;52;52;55;53;54;53;54 06:35:10 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;70;77;79;72;73;70;72;72;70;69 06:35:10 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;88;88;88;89;88;88;90;87;87;88 06:35:10 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;151;151;148;150;153;150;148;150;198;186 06:35:10 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;177;175;173;226;175;176;177;224;225;192 06:35:10 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;67;69;69;69;68;66;68;72;84 06:35:10 INFO - PROCESS | 1649 | __end_tp_report 06:35:10 INFO - PROCESS | 1649 | __start_cc_report 06:35:10 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2085 06:35:10 INFO - PROCESS | 1649 | __end_cc_report 06:35:10 INFO - PROCESS | 1649 | __startTimestamp1479738910645__endTimestamp 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | ------- Summary: start ------- 06:35:10 INFO - PROCESS | 1649 | Number of tests: 14 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:124.00 Median:114.00 stddev:21.64 (19.0%) stddev-sans-first:22.58 06:35:10 INFO - PROCESS | 1649 | Values: 113.0 115.0 114.0 114.0 113.0 167.0 163.0 113.0 114.0 114.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:130.50 stddev:21.41 (16.4%) stddev-sans-first:21.33 06:35:10 INFO - PROCESS | 1649 | Values: 151.0 114.0 117.0 177.0 137.0 124.0 116.0 112.0 141.0 112.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:2.41 (1.3%) stddev-sans-first:1.99 06:35:10 INFO - PROCESS | 1649 | Values: 188.0 182.0 185.0 187.0 182.0 182.0 183.0 182.0 181.0 185.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:79.10 Median:78.00 stddev:17.69 (22.7%) stddev-sans-first:18.76 06:35:10 INFO - PROCESS | 1649 | Values: 79.0 81.0 80.0 109.0 60.0 58.0 61.0 78.0 107.0 78.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:80.50 stddev:12.27 (15.2%) stddev-sans-first:12.13 06:35:10 INFO - PROCESS | 1649 | Values: 91.0 71.0 96.0 70.0 69.0 68.0 90.0 68.0 68.0 93.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:1.51 06:35:10 INFO - PROCESS | 1649 | Values: 87.0 84.0 86.0 84.0 87.0 84.0 88.0 86.0 86.0 84.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.84 (0.9%) stddev-sans-first:0.67 06:35:10 INFO - PROCESS | 1649 | Values: 93.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:87.50 Median:87.00 stddev:2.01 (2.3%) stddev-sans-first:2.13 06:35:10 INFO - PROCESS | 1649 | Values: 88.0 84.0 87.0 89.0 87.0 87.0 87.0 87.0 87.0 92.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.10 (2.1%) stddev-sans-first:1.12 06:35:10 INFO - PROCESS | 1649 | Values: 52.0 52.0 52.0 52.0 52.0 55.0 53.0 54.0 53.0 54.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.00 stddev:3.24 (4.5%) stddev-sans-first:3.32 06:35:10 INFO - PROCESS | 1649 | Values: 70.0 77.0 79.0 72.0 73.0 70.0 72.0 72.0 70.0 69.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:88.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 06:35:10 INFO - PROCESS | 1649 | Values: 88.0 88.0 88.0 89.0 88.0 88.0 90.0 87.0 87.0 88.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:151.00 stddev:17.94 (11.9%) stddev-sans-first:18.82 06:35:10 INFO - PROCESS | 1649 | Values: 151.0 151.0 148.0 150.0 153.0 150.0 148.0 150.0 198.0 186.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.00 Median:184.50 stddev:23.37 (12.7%) stddev-sans-first:24.15 06:35:10 INFO - PROCESS | 1649 | Values: 177.0 175.0 173.0 226.0 175.0 176.0 177.0 224.0 225.0 192.0 06:35:10 INFO - PROCESS | 1649 | 06:35:10 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:69.00 stddev:5.16 (7.5%) stddev-sans-first:5.43 06:35:10 INFO - PROCESS | 1649 | Values: 68.0 67.0 69.0 69.0 69.0 68.0 66.0 68.0 72.0 84.0 06:35:10 INFO - PROCESS | 1649 | -------- Summary: end -------- 06:35:10 INFO - PROCESS | 1649 | 06:35:11 INFO - TEST-INFO | 1649: exit 0 06:35:11 INFO - TEST-OK | kraken | took 61443ms 06:35:11 INFO - SUITE-END | took 1181s 06:35:11 INFO - Completed test suite (00:19:41) 06:35:11 INFO - javascript benchmark 06:35:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3061.7703672224584, 3278.22961604023, 3315.1049216711504, 3327.60068791745, 3332.250243695168, 5587.357453892803, 5602.635828671134, 5659.801906933253, 5681.204631351604, 5694.601377903548, 5360.731963401834, 5613.186088017235, 5770.307563092435, 5778.0, 5811.273590801139, 5095.719735414551, 5103.004914041763, 5154.175331946889, 5163.81926632568, 5163.993021360818, 5242.55438287746, 5271.894562108756, 5290.709011004393, 5295.232191332255, 5297.761600727958, 5260.158374660036, 5264.368275806889, 5295.4704529546925, 5314.627976041678, 5340.958513089949, 5091.159958606825, 5177.896442071137, 5179.067767801795, 5201.947980520222, 5212.348456442945, 5095.745212739348, 5159.458256883011, 5173.689578625257, 5175.353080864892, 5176.844694659167, 4787.473377928425, 5622.269105016363, 5747.166660834156, 5767.567432442584, 5805.332386775491, 6199.380061993809, 6207.937920620826, 6209.223847019122, 6232.470240029557, 6233.719482623293, 5254.789808407659, 6106.764041036368, 6183.690815459254, 6281.623102613858, 6332.778352757631, 5667.688277144755, 5712.80005199816, 5783.942160578425, 5796.362400135981, 5852.122181672783, 6278.576338045009, 6279.686015699288, 6330.240371155399, 6333.714982825784, 6351.682415879188, 6118.265808102967, 6141.57009009374, 6150.3849615039035, 6155.076738489262, 6162.907556386658, 6092.482139018144, 6116.405802871558, 6118.019533855675, 6144.354842741448, 6206.441420272107, 6180.752769889244, 6197.194364732646, 6197.5351848611535, 6202.44178023971, 6213.906791398133, 6173.418011784561, 6221.160143380676, 6228.5017198624, 6242.188515492957, 6243.875122497525, 4792.041591681608, 4872.4883887192045, 4884.413870335582, 4889.828855990023, 4926.778294976734, 4381.298992161239, 4406.603405693503, 4408.294672852328, 4437.267850804646, 4477.977610111929, 4902.8774280643265, 5773.566982476331, 5818.4763371296785, 5848.61983971049, 5849.210356601804, 6100.853682747615, 6133.8855759873895, 6316.463100636405, 6318.0, 6326.778562750282, 5625.043742563774, 5667.319921609434, 5670.347909990322, 5759.136129580596, 5786.88426231473, 5269.473052694777, 5310.840674779763, 5314.4951229633125, 5315.973420132875, 5324.281222035053, 6289.49684025286, 6308.211473565804, 6309.558330916883, 6313.652749098752, 6316.431521163117, 5997.310309314485, 6192.885280649526, 6225.221847269091, 6260.686965651608, 6292.056191571118, 6318.652474113968, 6323.7786677466065, 6324.146240257506, 6335.113084168128, 6351.587146835441, 6140.692965351804, 6203.4416902479, 6209.223847019122, 6212.192414986203, 6219.440250377488, 5340.279062326536, 5717.313922329347, 6171.660558669317, 6182.969085154546, 6196.628202307876, 6094.9085763714465, 6119.478862428838, 6128.697664213171, 6146.846328841814, 6152.16945712341, 6103.145559621746, 6106.976420671822, 6111.8777624446375, 6112.755489780359, 6121.908171377522, 5786.652800831964, 5855.502282305882, 5900.616459930091, 5951.145525664544, 5963.314218864886, 6059.969700151471, 6126.609281623611, 6127.678242056309, 6139.676133582418, 6156.969215153896, 5976.372480889444, 5977.790777322773, 5986.521078313812, 5990.850228744316, 6054.364291749479], "value": 5670.8590336762045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.5492327365729, 489.96325275604295, 509.98725031874187, 511.2663328124142, 520.0872468817223, 29617.851910740443, 39329.410058849106, 40805.79597102014, 40862.0, 42083.78958105213, 39480.21039579207, 39805.0, 39966.60033399672, 39975.60024399755, 40021.39967900477, 9289.907100928971, 9483.573239204217, 9486.81026379472, 9489.098535639137, 9492.0, 8464.195901389357, 8668.219860212579, 8676.652933882637, 8789.0, 8796.824063518727, 11125.833112503318, 11379.146564007691, 11385.0, 11544.53821847125, 11550.653480395602, 11241.1569132315, 11288.943555282252, 11300.208985371028, 11324.886751132548, 11328.150388720796, 8324.29243514302, 8549.444286121383, 11139.944300278528, 11198.272112312674, 11216.775664486746, 38855.80572097121, 39145.825625231286, 39317.803410982764, 41043.973900652425, 41290.38064429036, 3316.817575033372, 3317.950230746517, 3321.8339083045753, 3342.8300094966567, 3416.009357286384, 29313.85343073271, 29500.11499655014, 29681.554776678367, 29873.0, 30197.547036794465, 3161.272907231327, 3192.680731926812, 3215.8070515769127, 3220.388126256027, 3298.670132986706, 492724.0, 495668.0, 498467.0, 499617.0, 501461.49269253784, 50334.24498632523, 51171.0, 57517.23517325107, 58642.706786465795, 58969.41030589639, 60851.78296434046, 61504.38495615076, 61895.38104618896, 61938.69030654818, 62084.68957655273, 15389.230538473032, 15551.455699050479, 15554.9222253888, 15560.377584896703, 15564.610884727746, 11978.981786548242, 12112.939435302767, 12116.636500904988, 12128.818067728991, 12142.878571214174, 14460.493882714054, 14880.627984300263, 14910.403583856742, 15239.771403428957, 16644.58388540272, 5700.828975130753, 6621.370969757865, 6675.599464032173, 6703.698333575001, 6747.0, 842.2966822703112, 909.5179554835947, 973.8539219117189, 1053.8946105389552, 1073.3988966178965, 29105.41789164205, 29344.0, 29348.266293342836, 30139.849300753795, 30274.394512110073, 1012.6810054832704, 1057.0592172966067, 1072.7264547540483, 1099.230538622967, 1113.2485572238868, 14685.339159737838, 14862.777058344134, 15240.314185861664, 15454.609085182388, 15539.45611903578, 624473.0, 644097.0, 644281.0, 650638.746806263, 650955.0, 49494.0, 50238.0, 56254.71872640611, 56493.0, 57563.13655295174, 1268.5433244032326, 1301.7113058072441, 1326.7280207557428, 1330.0955350361846, 1371.2389623758909, 29638.259043524085, 29954.0, 30100.946466873553, 30226.69773302239, 30502.187226596674, 50177.74911125421, 51062.74468627633, 51070.74464627653, 51130.74434627952, 51166.48833511766, 483891.58054209506, 635580.0, 641373.7931310313, 657062.0, 830572.0, 24491.63262551099, 24574.385640359134, 24781.752182477943, 25004.749952500242, 25021.249362519153, 44272.33591496194, 45247.32129018133, 46142.53857461383, 46217.0, 48509.27236091532, 29335.973240936466, 35753.0, 36112.29157813122, 38874.22251555009, 39252.60747392604, 36916.446253306756, 37400.06499837526, 37850.6252112201, 38556.614433856426, 38714.80642596882, 37357.25285494329, 37364.813175933945, 37655.81172094122, 38407.42388864113, 38510.45958161642, 30867.845660771553, 32585.34829303448, 33635.66364336325, 33674.49488257629, 33832.98501044972, 21699.240526581492, 23868.045278188678, 24613.87693061523, 24737.628935565623, 24771.752282476944], "value": 19589.46609734346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.7035684489763, 367.2929610499789, 368.82112175594835, 375.86281007432285, 397.7235821104332, 14316.642083947894, 14595.0, 15396.769048464283, 19193.904030479862, 19890.602187956232, 18482.44552663419, 19676.901615491937, 19769.406917792454, 19830.70253946192, 19980.700289495668, 5733.140028995653, 6106.786262480814, 6107.297660769024, 6112.205413296265, 6122.510199184077, 5210.791568337262, 5848.590598658096, 5992.34084250732, 6039.879202415949, 6055.303640081403, 5322.494363035525, 5711.086226203808, 5795.101759227306, 5991.310999235078, 5994.760209591611, 5750.7412166452405, 5892.351841297454, 5900.0, 5903.203067585864, 5906.409359064102, 4889.364382630235, 5079.796808127708, 5144.922826157611, 5147.459516750762, 5157.896842063175, 20079.598408031903, 24466.755332446803, 24493.38766530833, 24789.380265493506, 24934.251972440674, 2023.5042414608515, 2217.81148602369, 2220.9888950555305, 2236.105557776886, 2445.3886528367907, 9839.655612053544, 10453.268271221017, 13178.143420677625, 14046.367913443817, 15371.461998830098, 1771.3003363671278, 1789.6062866169423, 1796.5418818201274, 1867.5517875709868, 1931.4881556387568, 113677.0, 121288.0840724339, 121505.0, 121579.15523093934, 121860.0, 9214.677486287948, 10889.782204355868, 11157.553697852158, 11216.345216735126, 11417.143714221467, 14086.366113524917, 14140.717185656229, 14142.36359363831, 14164.858351416355, 14410.567682969318, 7950.880736788837, 8232.794180145544, 8247.340212782963, 8352.582370881431, 8398.958005210057, 8044.839103217902, 8066.959665201636, 8068.152843951418, 8224.259816616413, 8232.958835205785, 4393.780310984438, 4403.776093073042, 4410.779461026936, 4433.556644335541, 4512.503624601291, 1560.4538411555864, 1880.3982725527974, 1883.7174423836534, 1912.7704675438758, 1922.875327592662, 805.3396215103676, 881.3566096749408, 911.5533388639651, 940.3182692547985, 1029.505837198134, 20226.59546809085, 20884.37346879596, 20945.685814712488, 21006.2647807326, 21094.57810843805, 895.6372669069037, 902.9006809250977, 927.5547737086237, 933.570557543524, 968.2254196642658, 10455.84316235257, 10515.369077855354, 10875.564977400974, 10917.126629869592, 10938.234323597431, 560724.0, 562158.0, 563663.0, 570980.0, 573543.0, 92849.0, 98670.50664746774, 111288.0, 111657.0, 120635.0, 866.8959724833061, 870.9608067636972, 933.9813203735887, 935.8876934767871, 937.6483818568037, 17177.31290748406, 19032.904835475732, 19037.333693320666, 19047.52381190426, 19131.426057218305, 46477.53522464867, 49064.2640360402, 52334.73832630813, 52415.47584524258, 53659.73170134125, 72983.90524142036, 75669.48661026859, 75959.62020189864, 77684.61157694175, 78491.60754196193, 6710.530262881552, 6870.690818913061, 7082.433405327664, 7125.394341481032, 7273.490855640054, 10340.844887326846, 11995.580154694893, 12015.399230038638, 12289.877101228873, 12377.443015064524, 10658.573657053632, 10703.090237329914, 10733.570657173626, 10883.401412922365, 10924.180686448708, 9403.106704863027, 10174.186065114922, 10417.16662667, 10748.140148788234, 10838.620648277272, 9312.255019598551, 10270.794584108426, 10317.587296508056, 10325.32885362449, 10388.428636424767, 7009.544379615309, 7700.730474433368, 7767.6116194188935, 7938.5236885784725, 8138.226868447488, 10584.735381615521, 10611.840822387825, 10783.245172837826, 10858.34849909008, 11193.944030280123], "value": 9213.714289997008, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.4317359403422, 1503.9172845493497, 1588.340838552001, 1677.865770738341, 1769.1950162675985, 7985.48094373866, 8431.536265505394, 8447.619857106429, 8453.112423195567, 8453.450525715823, 7389.741359052434, 7586.279303466162, 8031.196880311966, 8159.388045896551, 8170.714025009126, 2287.691161693171, 2321.8374713770045, 2326.8603883766946, 2327.3483424641126, 2328.1036800831635, 2307.6077066898583, 2336.8714720690355, 2352.2590384028977, 2355.7644235576477, 2358.127492827656, 2020.191923230712, 2089.9059542320633, 2092.79072092791, 2222.155580879272, 2298.310506847939, 2281.6577513373045, 2296.8966396512196, 2297.1041293895396, 2300.7354154272307, 2302.2172461363216, 2285.5029955379255, 2321.013569233086, 2322.953540929189, 2328.382978510696, 2332.9066837326486, 8224.917750822475, 8298.21166989135, 8300.70947516834, 8312.085670576233, 8340.040895296997, 5015.222640490693, 5148.639595228336, 5176.922346164811, 5179.81870634526, 5200.557952574036, 3284.490903909898, 3335.1328654549748, 3507.8947631571095, 3577.266660334651, 3579.6778289954027, 1055.7782865598233, 1079.5034284229264, 1080.1304949515636, 1100.0979197058416, 1101.9008289253925, 10771.24601277911, 10848.945755271252, 10955.726106847313, 10960.232783705143, 10971.561137554489, 4427.203103441347, 4427.667924905645, 4642.675012749075, 4652.116097941448, 4669.392978912788, 9364.859527107099, 9381.202597779265, 9386.530673466299, 9387.906120938704, 9389.483578403253, 5126.026055049529, 5138.97430512845, 5139.486051394831, 5152.4847515248175, 5165.354330708661, 4634.119517291704, 4651.348811166439, 4706.435227772621, 4708.788104535236, 4710.552497512731, 3255.8209298488337, 3650.178709790276, 3731.8134093295225, 3754.0, 3784.7539909905768, 1234.851817781854, 1237.1649136832602, 1237.412229191127, 1247.2454165230088, 1262.9684257893625, 602.4607975861579, 669.718718138383, 853.1894700034992, 923.2475532441173, 929.9628014879329, 4780.665353425227, 5447.237386765856, 5726.885462290814, 5762.798302059407, 5900.20347253124, 783.561205724796, 846.4667259626395, 885.9069797671152, 918.875951746519, 961.7595601099725, 3346.330733853239, 3835.7506762060375, 3839.2897313997, 3939.290927632996, 3948.9012774680864, 9837.311388202757, 10042.246831487668, 10163.898361016443, 10179.949100254451, 10219.0, 10126.54430550627, 10128.949355253177, 10133.594656213816, 10139.898601014043, 10143.898561014295, 7396.741114060982, 7674.923250767532, 7776.65005074773, 7832.099308579473, 7839.137694853562, 5698.8290351289525, 5885.882282354414, 5897.7935772247765, 5922.378150294157, 5940.61386009908, 9026.819463610822, 9354.017828127948, 9460.2904782143, 9481.478518681539, 9487.905120948979, 9556.331056825955, 9574.808503830023, 9582.233421326138, 9656.420614763138, 9708.85436718464, 6994.650267486503, 7079.646017699198, 7128.037714908421, 7147.142342918883, 7151.499395042297, 7466.589337586485, 7631.771046868603, 7659.731909383145, 7661.272179142972, 7679.308862202429, 6800.52396332252, 6884.896726549206, 6943.618101004493, 6963.40811031068, 7053.118360204974, 6780.0, 6853.0, 6863.347981941708, 6921.169459664872, 6944.826379340355, 6402.6158430494315, 6474.1907261592305, 6486.286508484157, 6553.836154096186, 6566.80299591013, 4689.249720044776, 4780.498047704941, 4813.24757950234, 4835.854924352275, 4957.702537847741, 4831.396075490564, 4889.095517329305, 4922.3082687984015, 4962.5533701967, 4994.225894986283], "value": 4384.611419432464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.6458014962195, 304.69987062743206, 308.25248771728565, 312.5967501922519, 313.40609544912405, 4892.87767805805, 5102.540771330579, 5103.004914041759, 5129.435762066169, 5151.253068305094, 4078.143589846136, 4274.935875961855, 4872.731999740013, 4989.0, 5121.078205922932, 3544.5923718772415, 3638.7089032877307, 3711.220643664827, 3869.613038696136, 3937.7243592948507, 3308.5111699735094, 3891.20230352777, 3928.2536318099615, 3953.4069889516513, 3955.92314035131, 3868.477755503013, 3890.7471014383973, 3895.3377925752548, 3896.5129358830145, 3905.902352441183, 3458.5330980317585, 3693.4644476550884, 3753.2868754936476, 3830.448668289347, 3892.4355968384566, 3131.8590663419973, 3403.9659603403898, 3407.1993081625797, 3408.244753951717, 3420.401429749784, 4888.266759985995, 4907.1657818171, 4932.926006109911, 4939.777710003059, 4941.827036053721, 3573.9456860226296, 3697.1699008990454, 3977.5028121484816, 4132.483439570054, 4391.780410979471, 5283.339582552181, 5285.762140703678, 5290.444503327172, 5328.866778330573, 5329.067413202674, 3724.608916063801, 3727.8192257529377, 3730.1793605406765, 3744.588095309514, 3747.9063023424633, 5142.820001299937, 5479.123340265578, 5508.283923090013, 5520.1443776214755, 5527.585431092644, 3326.800391976489, 3426.989038233727, 3521.6126226115107, 3670.5317872850537, 3734.589195188527, 3776.546814382292, 3777.338965680995, 3803.9568345323632, 3809.6190380962234, 3810.028442747082, 4381.474223093249, 4388.232059389594, 4392.824287028547, 4394.572233657752, 4422.447194100738, 4709.62323014158, 4783.904321913542, 4935.432425271068, 5170.45710200431, 5403.352357344873, 1229.692576855786, 1238.2446707508414, 1239.2750241108872, 1242.912996090283, 1252.2361359570655, 780.0989856715503, 834.7871292820292, 852.5055467828645, 854.2354592639552, 857.8970523537091, 4430.9869288883865, 4768.594669453136, 4893.437254715754, 5015.423226329019, 5024.57291130258, 4327.675424343187, 5257.7896884124975, 5475.178723191473, 5489.33767122945, 5557.249771280906, 4250.55369186231, 4810.725157833177, 5012.8496145115705, 5014.247862820606, 5067.949320506821, 2389.7610238975963, 2473.9752602473745, 2482.5973325071277, 2516.993202718927, 2631.9868400658256, 4945.554900059012, 5041.193409054534, 5242.475752424803, 5480.657238976397, 5504.4770746778995, 4723.95276047242, 4746.477887432379, 4793.832215872428, 4977.0, 5280.762365693553, 4841.8547443576745, 5012.7994880205115, 5166.767495462638, 5370.892582148414, 5441.265429167091, 5199.766010529611, 5247.1866860636665, 5315.9468405317, 5323.733813309396, 5328.25404443386, 5072.087024335655, 5141.717205553581, 5162.793488260428, 5181.5595674368105, 5256.6320357574605, 5764.654120752768, 5765.798197063083, 5781.710914454176, 5782.479576838105, 5808.593398462067, 3787.3182827091387, 3789.621037896188, 3792.544894612664, 3799.4680744696316, 3810.218905124498, 5234.0, 5270.446603106619, 5280.735963201748, 5536.944630553804, 5586.13414920688, 4723.661787415252, 5322.148456246982, 5422.319081084876, 5422.5119739223655, 5432.59255555827, 4577.793999269974, 4823.684631376888, 4955.5044495550155, 5027.59227416324, 5379.892402152013, 5024.723640199824, 5130.999455106218, 5281.313429254173, 5296.549793267615, 5305.814296499604, 5149.0, 5306.814261500829, 5389.353277606712, 5425.294711687613, 5427.918581221206, 4057.695672824609, 5197.324347834758, 5306.593752655473, 5308.362996440452, 5316.521513063843], "value": 3877.5940372049117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.8066260784373, 697.4420463629097, 738.7016969232376, 749.6926260233305, 793.3296364571936, 14210.786838197422, 14945.551633450987, 14955.626109347259, 14982.700345993073, 15005.62485937852, 13326.267055311953, 13524.459021639148, 13562.118462299968, 13567.864321356808, 13629.25039122848, 666.4468491152332, 688.6315821035747, 693.012457248419, 824.3281725393784, 833.9374546908975, 837.5519097282955, 841.4446465332882, 852.5992783391796, 852.6504133305347, 859.4070091636781, 884.8672699095123, 888.7200531832484, 889.1863944490816, 892.1346294094718, 892.2460520859863, 690.0, 715.7995761186876, 881.7707396077002, 882.7660669922476, 885.4775682347381, 542.6391449685954, 544.5235419008368, 545.0624925128773, 546.519063224364, 650.391883588846, 13856.30718464083, 13871.79192312106, 13888.722225555533, 13897.0, 13926.58220253394, 12709.809352859622, 12826.422810973529, 12895.355232238444, 13055.673608159777, 13195.670108247275, 11899.226550274205, 11964.102692298113, 12032.759344813056, 12053.276803391824, 12193.329366884818, 4310.418093557361, 4338.175746608134, 4343.0, 4511.729296242236, 4532.229520981408, 85401.0, 87628.0, 88708.0, 88713.22573548493, 89211.55394223052, 8613.310935125175, 10356.430396328124, 10447.895521044693, 10477.37135771856, 10768.14644119309, 19144.61710765777, 19257.711134332996, 19401.902990484956, 19443.708344374845, 19759.60480790376, 7360.78589290419, 7393.889091663629, 7415.85168296631, 7486.625668716542, 7517.548947063194, 4151.6886233532605, 4225.936610950838, 4245.936310955338, 4254.383114448449, 4343.891402714894, 3133.341998180362, 3213.4697774867354, 3566.4828599852876, 3725.776453412804, 3726.0312318796764, 668.2348710726288, 675.5237557521936, 680.8195828105556, 682.0417313674295, 689.2280645676774, 490.35763150273254, 584.4564554963839, 649.2086216495594, 654.0777503719733, 660.600336796241, 8652.091530389345, 8678.522681252465, 8745.60644770974, 8835.33734969867, 8854.317390306449, 544.1782907809157, 546.4398991034142, 639.2935805934468, 644.6067898581862, 664.226176504367, 4053.9797301013896, 4236.957630423718, 4369.672274579356, 4404.141192467502, 4447.177272204653, 84109.0, 104297.77091546923, 105988.0, 110925.44537277262, 111260.94144866492, 95012.57481137788, 97047.0, 97848.0, 98800.0, 99041.50479247558, 65556.01665975014, 83290.16709832824, 85144.0, 85781.0, 89539.55230223938, 8434.451760635538, 8584.957075214585, 8590.484570925764, 8599.355048371272, 8672.479651220947, 46578.0, 47472.7626361866, 55427.72286138544, 56257.0, 57297.85404291807, 66738.0, 67423.66288168528, 68038.0, 68915.65542172457, 69960.65019674868, 11831.290122592327, 12038.277703337828, 12162.270263784201, 12310.81533777012, 12844.35778211067, 13099.41052652652, 13495.25776082325, 13838.993345964578, 14406.351714173097, 14460.0, 12569.308688021882, 12770.361481926053, 13025.478980841039, 13226.206427614374, 13615.523456679364, 12013.75972480563, 12574.182678125893, 12625.621231363073, 12724.809127863275, 13238.735225295248, 12439.626811195678, 12796.0, 12820.038497112568, 13120.475180993035, 13207.735845283232, 10321.709786276715, 11136.0, 11241.38172400493, 11366.033887119687, 11496.54013839437, 10506.842397363893, 10722.287209786566, 11012.614558490415, 11085.0, 11138.10895128404], "value": 7195.552939597489, "unit": "score"}], "name": "dromaeo_css", "value": 7073.246334022508, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 114.0, 113.0, 167.0, 163.0, 113.0, 114.0, 114.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 117.0, 177.0, 137.0, 124.0, 116.0, 112.0, 141.0, 112.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 182.0, 185.0, 187.0, 182.0, 182.0, 183.0, 182.0, 181.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 80.0, 109.0, 60.0, 58.0, 61.0, 78.0, 107.0, 78.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 96.0, 70.0, 69.0, 68.0, 90.0, 68.0, 68.0, 93.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 87.0, 84.0, 88.0, 86.0, 86.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 89.0, 87.0, 87.0, 87.0, 87.0, 87.0, 92.0], "value": 87.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 55.0, 53.0, 54.0, 53.0, 54.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 79.0, 72.0, 73.0, 70.0, 72.0, 72.0, 70.0, 69.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 89.0, 88.0, 88.0, 90.0, 87.0, 87.0, 88.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 148.0, 150.0, 153.0, 150.0, 148.0, 150.0, 198.0, 186.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 173.0, 226.0, 175.0, 176.0, 177.0, 224.0, 225.0, 192.0], "value": 192.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 69.0, 69.0, 68.0, 66.0, 68.0, 72.0, 84.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1496.8999999999999, "alertThreshold": 2.0}]} 06:35:11 INFO - Return code: 0 06:35:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:11 INFO - # TBPL SUCCESS # 06:35:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:35:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:35:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:35:11 INFO - 2016-11-21 06:35:11.914 screenresolution[1653:9751] starting screenresolution argv=screenresolution get 06:35:11 INFO - 2016-11-21 06:35:11.918 screenresolution[1653:9751] Display 0: 1600x1200x32@60 06:35:11 INFO - 2016-11-21 06:35:11.924 screenresolution[1654:9754] starting screenresolution argv=screenresolution list 06:35:11 INFO - Available Modes on Display 0 06:35:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:35:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:35:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:35:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:35:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:35:12 INFO - Intel Iris: 06:35:12 INFO - Chipset Model: Intel Iris 06:35:12 INFO - Type: GPU 06:35:12 INFO - Bus: Built-In 06:35:12 INFO - VRAM (Dynamic, Max): 1536 MB 06:35:12 INFO - Vendor: Intel (0x8086) 06:35:12 INFO - Device ID: 0x0a2e 06:35:12 INFO - Revision ID: 0x0009 06:35:12 INFO - Displays: 06:35:12 INFO - Display: 06:35:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:35:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:35:12 INFO - Display Serial Number: 1600x1200 60 06:35:12 INFO - Main Display: Yes 06:35:12 INFO - Mirror: Off 06:35:12 INFO - Online: Yes 06:35:12 INFO - Rotation: Supported 06:35:12 INFO - Return code: 0 06:35:12 INFO - Running post-action listener: _resource_record_post_action 06:35:12 INFO - [mozharness: 2016-11-21 14:35:12.167094Z] Finished run-tests step (success) 06:35:12 INFO - Running post-run listener: _resource_record_post_run 06:35:12 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37483520; Write bytes: 690286592; Read time: 241; Write time: 1985 06:35:12 INFO - TinderboxPrint: CPU usage
54.0% 06:35:12 INFO - TinderboxPrint: I/O read bytes / time
37,483,520 / 241 06:35:12 INFO - TinderboxPrint: I/O write bytes / time
690,286,592 / 1,985 06:35:12 INFO - TinderboxPrint: CPU idle
2,233.3 (46.0%) 06:35:12 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 06:35:12 INFO - TinderboxPrint: CPU user
1,645.6 (33.9%) 06:35:12 INFO - TinderboxPrint: Swap in / out
420,962,304 / 0 06:35:12 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 368155136; Write bytes: 367536128; Read time: 27439; Write time: 789 06:35:12 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35697664; Write bytes: 303192064; Read time: 227; Write time: 1147 06:35:12 INFO - Running post-run listener: _upload_blobber_files 06:35:12 INFO - Blob upload gear active. 06:35:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:12 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 06:35:12 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'] 06:35:12 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 06:35:13 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:35:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:35:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:13 INFO - (blobuploader) - INFO - Done attempting. 06:35:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:35:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:14 INFO - (blobuploader) - INFO - Done attempting. 06:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:17 INFO - (blobuploader) - INFO - Done attempting. 06:35:17 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:17 INFO - Return code: 0 06:35:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e010916967ec8bc2c3be1ecd21a42be81b4a9c7557a7599c8ac0380656e022e79760a4707b8bccd9f5059ba1ab5cb78aa2df3c1fd3fd89d1b9739e54d4ae55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b91cf0e476b33b1f3a974c7b3946b322dcc9fbd7071a3e00877bbb33bd045220bb59ff9514c7ebde498b90d0bc9663e480312e5542a78a47e74c88876e7fb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2602bb2bc347e0c84ae0cd82bed77ecc09b97e27cfe7f23cca0f0fe925f6536070acb9ce94fb45e10a3b8e9e105a711c19d5c354ac20b133064f8ed1b33c167d"} 06:35:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:17 INFO - Contents: 06:35:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e010916967ec8bc2c3be1ecd21a42be81b4a9c7557a7599c8ac0380656e022e79760a4707b8bccd9f5059ba1ab5cb78aa2df3c1fd3fd89d1b9739e54d4ae55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b91cf0e476b33b1f3a974c7b3946b322dcc9fbd7071a3e00877bbb33bd045220bb59ff9514c7ebde498b90d0bc9663e480312e5542a78a47e74c88876e7fb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2602bb2bc347e0c84ae0cd82bed77ecc09b97e27cfe7f23cca0f0fe925f6536070acb9ce94fb45e10a3b8e9e105a711c19d5c354ac20b133064f8ed1b33c167d"} 06:35:17 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:17 INFO - Copying logs to upload dir... 06:35:17 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.179131 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-21 06:35:17.139416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:17.143253) ========= 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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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/70e010916967ec8bc2c3be1ecd21a42be81b4a9c7557a7599c8ac0380656e022e79760a4707b8bccd9f5059ba1ab5cb78aa2df3c1fd3fd89d1b9739e54d4ae55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b91cf0e476b33b1f3a974c7b3946b322dcc9fbd7071a3e00877bbb33bd045220bb59ff9514c7ebde498b90d0bc9663e480312e5542a78a47e74c88876e7fb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2602bb2bc347e0c84ae0cd82bed77ecc09b97e27cfe7f23cca0f0fe925f6536070acb9ce94fb45e10a3b8e9e105a711c19d5c354ac20b133064f8ed1b33c167d"} build_url:https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e010916967ec8bc2c3be1ecd21a42be81b4a9c7557a7599c8ac0380656e022e79760a4707b8bccd9f5059ba1ab5cb78aa2df3c1fd3fd89d1b9739e54d4ae55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b91cf0e476b33b1f3a974c7b3946b322dcc9fbd7071a3e00877bbb33bd045220bb59ff9514c7ebde498b90d0bc9663e480312e5542a78a47e74c88876e7fb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2602bb2bc347e0c84ae0cd82bed77ecc09b97e27cfe7f23cca0f0fe925f6536070acb9ce94fb45e10a3b8e9e105a711c19d5c354ac20b133064f8ed1b33c167d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:17.182064) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:17.182681) ========= 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.rlZxce04Ld/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.HzS0RTM3Cp/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005270 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:17.232641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 06:35:17.232961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 06:35:17.233323) ========= ========= Total master_lag: 0.19 =========