builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0329 starttime: 1475979495.27 results: success (0) buildid: 20161008164919 builduid: a3d74d26f0594c04aff48b63d9cb7e00 revision: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.270241) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.274192) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.274934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.302501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.302816) ========= 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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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-10-08 19:18:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-10-08 19:18:15 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.486294 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.813698) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.814019) ========= 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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.031281 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:15.865449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-08 19:18:15.865773) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 --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 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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-10-08 19:18:15,955 truncating revision to first 12 chars 2016-10-08 19:18:15,955 Setting DEBUG logging. 2016-10-08 19:18:15,955 attempt 1/10 2016-10-08 19:18:15,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7a7ba250bb2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-08 19:18:16,587 unpacking tar archive at: mozilla-central-7a7ba250bb2f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.916450 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-08 19:18:16.907255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:16.907521) ========= script_repo_revision: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:16.907894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:16.908156) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 19:18:16.953966) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-08 19:18:16.954241) ========= /tools/buildbot/bin/python 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', '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18:17 INFO - MultiFileLogger online at 20161008 19:18:17 in /builds/slave/test 19:18:17 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 19:18:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:18:17 INFO - {'append_to_log': False, 19:18:17 INFO - 'base_work_dir': '/builds/slave/test', 19:18:17 INFO - 'blob_upload_branch': 'Firefox', 19:18:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:18:17 INFO - 'branch': 'Firefox', 19:18:17 INFO - 'buildbot_json_path': 'buildprops.json', 19:18:17 INFO - 'config_files': ('talos/mac_config.py',), 19:18:17 INFO - 'default_actions': ('clobber', 19:18:17 INFO - 'read-buildbot-config', 19:18:17 INFO - 'download-and-extract', 19:18:17 INFO - 'populate-webroot', 19:18:17 INFO - 'create-virtualenv', 19:18:17 INFO - 'install', 19:18:17 INFO - 'run-tests'), 19:18:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:18:17 INFO - 'download_minidump_stackwalk': True, 19:18:17 INFO - 'download_symbols': 'ondemand', 19:18:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:18:17 INFO - 'tooltool.py': '/tools/tooltool.py', 19:18:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:18:17 INFO - '/tools/misc-python/virtualenv.py')}, 19:18:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:18:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:18:17 INFO - 'installer_path': 'installer.exe', 19:18:17 INFO - 'log_level': 'info', 19:18:17 INFO - 'log_name': 'talos', 19:18:17 INFO - 'log_to_console': True, 19:18:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:18:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:18:17 INFO - 'opt_config_files': (), 19:18:17 INFO - 'pip_index': False, 19:18:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:17 INFO - 'cmd': ('bash', 19:18:17 INFO - '-c', 19:18:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:17 INFO - 'enabled': True, 19:18:17 INFO - 'halt_on_failure': False, 19:18:17 INFO - 'name': 'check_screen_resolution'},), 19:18:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:17 INFO - 'cmd': ('bash', 19:18:17 INFO - '-c', 19:18:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:17 INFO - 'enabled': True, 19:18:17 INFO - 'halt_on_failure': False, 19:18:17 INFO - 'name': 'check_screen_resolution'},), 19:18:17 INFO - 'run_cmd_checks_enabled': True, 19:18:17 INFO - 'sps_profile': False, 19:18:17 INFO - 'sps_profile_interval': 0, 19:18:17 INFO - 'suite': 'dromaeojs', 19:18:17 INFO - 'system_bits': '32', 19:18:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:18:17 INFO - 'title': 't-yosemite-r7-0329', 19:18:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:18:17 INFO - 'use_talos_json': True, 19:18:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:18:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:18:17 INFO - 'work_dir': 'build'} 19:18:17 INFO - [mozharness: 2016-10-09 02:18:17.173488Z] Running clobber step. 19:18:17 INFO - Running pre-action listener: _resource_record_pre_action 19:18:17 INFO - Running main action method: clobber 19:18:17 INFO - rmtree: /builds/slave/test/build 19:18:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:18:19 INFO - Running post-action listener: _resource_record_post_action 19:18:19 INFO - [mozharness: 2016-10-09 02:18:19.374696Z] Finished clobber step (success) 19:18:19 INFO - [mozharness: 2016-10-09 02:18:19.374818Z] Running read-buildbot-config step. 19:18:19 INFO - Running pre-action listener: _resource_record_pre_action 19:18:19 INFO - Running main action method: read_buildbot_config 19:18:19 INFO - Using buildbot properties: 19:18:19 INFO - { 19:18:19 INFO - "project": "", 19:18:19 INFO - "product": "firefox", 19:18:19 INFO - "script_repo_revision": "production", 19:18:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:18:19 INFO - "repository": "", 19:18:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:18:19 INFO - "buildid": "20161008164919", 19:18:19 INFO - "pgo_build": "False", 19:18:19 INFO - "basedir": "/builds/slave/test", 19:18:19 INFO - "buildnumber": 0, 19:18:19 INFO - "slavename": "t-yosemite-r7-0329", 19:18:19 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:18:19 INFO - "platform": "yosemite_r7", 19:18:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:18:19 INFO - "branch": "mozilla-central", 19:18:19 INFO - "repo_path": "mozilla-central", 19:18:19 INFO - "revision": "7a7ba250bb2f5a7cc7acf4b97145425c5292e894", 19:18:19 INFO - "stage_platform": "macosx64", 19:18:19 INFO - "builduid": "a3d74d26f0594c04aff48b63d9cb7e00", 19:18:19 INFO - "slavebuilddir": "test" 19:18:19 INFO - } 19:18:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:18:19 INFO - Running post-action listener: _resource_record_post_action 19:18:19 INFO - [mozharness: 2016-10-09 02:18:19.378117Z] Finished read-buildbot-config step (success) 19:18:19 INFO - [mozharness: 2016-10-09 02:18:19.378249Z] Running download-and-extract step. 19:18:19 INFO - Running pre-action listener: _resource_record_pre_action 19:18:19 INFO - Running main action method: download_and_extract 19:18:19 INFO - mkdir: /builds/slave/test/build/tests 19:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:19 INFO - trying https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:18:19 INFO - Downloading https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:18:20 INFO - Downloaded 1199 bytes. 19:18:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:18:20 INFO - Using the following test package requirements: 19:18:20 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:18:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:18:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:18:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'jsshell-mac.zip'], 19:18:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:18:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:18:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:18:20 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:18:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:18:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:18:20 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:18:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:18:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:18:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:18:20 INFO - Fetch https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:18:20 INFO - Http code: 200 19:18:20 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 19:18:20 INFO - x-amz-cf-id: pqIwO1ThauLO5NkeojEhJNidslA-agIBJb86z0ww9Wgv-nDdjruj2A== 19:18:20 INFO - x-amz-version-id: MisJgTgTQLzpanvcEhfBa6aZhORRXwUo 19:18:21 INFO - Expected file size: 16504568 19:18:21 INFO - Obtained file size: 16504568 19:18:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:18:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:18:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:18:27 INFO - Fetch https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:18:28 INFO - Http code: 200 19:18:28 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 19:18:28 INFO - x-amz-cf-id: HC_B0-yRIJEQUuqjw_pwF3IePqnVYIFxSCG8xl9_xPGsnAVjsj_TiQ== 19:18:28 INFO - x-amz-version-id: MisJgTgTQLzpanvcEhfBa6aZhORRXwUo 19:18:28 INFO - Expected file size: 16504568 19:18:28 INFO - Obtained file size: 16504568 19:18:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:18:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:18:34 INFO - Fetch https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:18:35 INFO - Http code: 200 19:18:35 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 19:18:35 INFO - x-amz-cf-id: l5gQqc8UwqzpiAaGFgxRDz3amLtE6MNuChaWvaxwNTrTCKPhhjQnjw== 19:18:35 INFO - x-amz-version-id: RikiBDt_pye89T0jCQ65_dWjL6k18QqP 19:18:36 INFO - Expected file size: 14409006 19:18:36 INFO - Obtained file size: 14409006 19:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:36 INFO - trying https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:18:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:18:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:18:39 INFO - Downloaded 111721414 bytes. 19:18:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:18:39 INFO - mkdir: /builds/slave/test/properties 19:18:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:18:39 INFO - Writing to file /builds/slave/test/properties/build_url 19:18:39 INFO - Contents: 19:18:39 INFO - build_url:https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:18:39 INFO - Running post-action listener: _resource_record_post_action 19:18:39 INFO - Running post-action listener: set_extra_try_arguments 19:18:39 INFO - [mozharness: 2016-10-09 02:18:39.859048Z] Finished download-and-extract step (success) 19:18:39 INFO - [mozharness: 2016-10-09 02:18:39.859193Z] Running populate-webroot step. 19:18:39 INFO - Running pre-action listener: _resource_record_pre_action 19:18:39 INFO - Running main action method: populate_webroot 19:18:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:18:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:18:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:18:39 INFO - '--tppagecycles', 19:18:39 INFO - '7'], 19:18:39 INFO - 'tests': ['tsvgm']}}, 19:18:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:18:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:18:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:18:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:18:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['damp', 'tps']}, 19:18:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'tests': ['damp', 'tps']}, 19:18:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['dromaeo_dom']}, 19:18:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:18:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['basic_compositor_video']}, 19:18:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:18:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'other-e10s': {'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['a11yr', 19:18:39 INFO - 'ts_paint', 19:18:39 INFO - 'tpaint', 19:18:39 INFO - 'sessionrestore', 19:18:39 INFO - 'sessionrestore_no_auto_restore', 19:18:39 INFO - 'tabpaint']}, 19:18:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['tsvgx', 19:18:39 INFO - 'tsvgr_opacity', 19:18:39 INFO - 'tart', 19:18:39 INFO - 'tscrollx', 19:18:39 INFO - 'cart']}, 19:18:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:18:39 INFO - 'tsvgr_opacity', 19:18:39 INFO - 'tart', 19:18:39 INFO - 'tscrollx', 19:18:39 INFO - 'cart']}, 19:18:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'talos_options': ['--disable-e10s'], 19:18:39 INFO - 'tests': ['tp5o']}, 19:18:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'tests': ['tp5o']}, 19:18:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'talos_options': ['--disable-e10s', 19:18:39 INFO - '--xperf_path', 19:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:39 INFO - 'tests': ['tp5n']}, 19:18:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:39 INFO - 'talos_options': ['--xperf_path', 19:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:39 INFO - 'tests': ['tp5n']}}, 19:18:39 INFO - 'talos.zip': {'path': '', 19:18:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:18:39 INFO - Running post-action listener: _resource_record_post_action 19:18:39 INFO - [mozharness: 2016-10-09 02:18:39.877924Z] Finished populate-webroot step (success) 19:18:39 INFO - [mozharness: 2016-10-09 02:18:39.878031Z] Running create-virtualenv step. 19:18:39 INFO - Running pre-action listener: _resource_record_pre_action 19:18:39 INFO - Running main action method: create_virtualenv 19:18:39 INFO - Creating virtualenv /builds/slave/test/build/venv 19:18:39 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 19:18:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:18:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:18:40 INFO - Using real prefix '/tools/python27' 19:18:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:18:40 INFO - Installing distribute.............................................................................................................................................................................................done. 19:18:43 INFO - Installing pip.................done. 19:18:43 INFO - Return code: 0 19:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:18:43 INFO - Reading from file tmpfile_stdout 19:18:43 INFO - Output received: 19:18:43 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) 19:18:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:43 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18:43 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 19:18:43 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 19:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:43 INFO - 'HOME': '/Users/cltbld', 19:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:43 INFO - 'LOGNAME': 'cltbld', 19:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:43 INFO - 'MOZ_NO_REMOTE': '1', 19:18:43 INFO - 'NO_EM_RESTART': '1', 19:18:43 INFO - 'PAGER': '/bin/cat', 19:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:43 INFO - 'PWD': '/builds/slave/test', 19:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:43 INFO - 'SHELL': '/bin/bash', 19:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:43 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:43 INFO - 'USER': 'cltbld', 19:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:43 INFO - 'XPC_FLAGS': '0x0', 19:18:43 INFO - 'XPC_SERVICE_NAME': '0', 19:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:44 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 19:18:44 INFO - Cleaning up... 19:18:44 INFO - Return code: 0 19:18:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:44 INFO - 'HOME': '/Users/cltbld', 19:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:44 INFO - 'LOGNAME': 'cltbld', 19:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:44 INFO - 'MOZ_NO_REMOTE': '1', 19:18:44 INFO - 'NO_EM_RESTART': '1', 19:18:44 INFO - 'PAGER': '/bin/cat', 19:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:44 INFO - 'PWD': '/builds/slave/test', 19:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:44 INFO - 'SHELL': '/bin/bash', 19:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:44 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:44 INFO - 'USER': 'cltbld', 19:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:44 INFO - 'XPC_FLAGS': '0x0', 19:18:44 INFO - 'XPC_SERVICE_NAME': '0', 19:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:44 INFO - Downloading/unpacking psutil>=3.1.1 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:18:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:48 INFO - Installing collected packages: psutil 19:18:48 INFO - Running setup.py install for psutil 19:18:48 INFO - building 'psutil._psutil_osx' extension 19:18:48 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 19:18:48 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 19:18:48 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 19:18:49 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 19:18:49 INFO - building 'psutil._psutil_posix' extension 19:18:49 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 19:18:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:18:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:18:49 INFO - ^ 19:18:49 INFO - 1 warning generated. 19:18:49 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 19:18:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:49 INFO - Successfully installed psutil 19:18:49 INFO - Cleaning up... 19:18:49 INFO - Return code: 0 19:18:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:18:49 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')]} 19:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:49 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')]} 19:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:49 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18:49 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 19:18:49 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 19:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:49 INFO - 'HOME': '/Users/cltbld', 19:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:49 INFO - 'LOGNAME': 'cltbld', 19:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:49 INFO - 'MOZ_NO_REMOTE': '1', 19:18:49 INFO - 'NO_EM_RESTART': '1', 19:18:49 INFO - 'PAGER': '/bin/cat', 19:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:49 INFO - 'PWD': '/builds/slave/test', 19:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:49 INFO - 'SHELL': '/bin/bash', 19:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:49 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:49 INFO - 'USER': 'cltbld', 19:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:49 INFO - 'XPC_FLAGS': '0x0', 19:18:49 INFO - 'XPC_SERVICE_NAME': '0', 19:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:18:49 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 19:18:49 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 19:18:49 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 19:18:49 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 19:18:49 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 19:18:49 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 19:18:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:18:52 INFO - Installing collected packages: mozsystemmonitor 19:18:52 INFO - Running setup.py install for mozsystemmonitor 19:18:52 INFO - Successfully installed mozsystemmonitor 19:18:52 INFO - Cleaning up... 19:18:52 INFO - Return code: 0 19:18:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:52 INFO - 'HOME': '/Users/cltbld', 19:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:52 INFO - 'LOGNAME': 'cltbld', 19:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:52 INFO - 'MOZ_NO_REMOTE': '1', 19:18:52 INFO - 'NO_EM_RESTART': '1', 19:18:52 INFO - 'PAGER': '/bin/cat', 19:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:52 INFO - 'PWD': '/builds/slave/test', 19:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:52 INFO - 'SHELL': '/bin/bash', 19:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:52 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:52 INFO - 'USER': 'cltbld', 19:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:52 INFO - 'XPC_FLAGS': '0x0', 19:18:52 INFO - 'XPC_SERVICE_NAME': '0', 19:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:52 INFO - Downloading/unpacking jsonschema==2.5.1 19:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:18:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:55 INFO - Downloading functools32-3.2.3-2.tar.gz 19:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:18:55 INFO - warning: no files found matching '*.txt' 19:18:55 INFO - no previously-included directories found matching 'build' 19:18:55 INFO - no previously-included directories found matching 'dist' 19:18:55 INFO - no previously-included directories found matching '.git*' 19:18:55 INFO - Installing collected packages: jsonschema, functools32 19:18:55 INFO - Running setup.py install for functools32 19:18:55 INFO - warning: no files found matching '*.txt' 19:18:55 INFO - no previously-included directories found matching 'build' 19:18:55 INFO - no previously-included directories found matching 'dist' 19:18:55 INFO - no previously-included directories found matching '.git*' 19:18:56 INFO - Successfully installed jsonschema functools32 19:18:56 INFO - Cleaning up... 19:18:56 INFO - Return code: 0 19:18:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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', '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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:18: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 functools32==3.2.3-2 19:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:56 INFO - 'HOME': '/Users/cltbld', 19:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:56 INFO - 'LOGNAME': 'cltbld', 19:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:56 INFO - 'MOZ_NO_REMOTE': '1', 19:18:56 INFO - 'NO_EM_RESTART': '1', 19:18:56 INFO - 'PAGER': '/bin/cat', 19:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:56 INFO - 'PWD': '/builds/slave/test', 19:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:56 INFO - 'SHELL': '/bin/bash', 19:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:56 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:56 INFO - 'USER': 'cltbld', 19:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:56 INFO - 'XPC_FLAGS': '0x0', 19:18:56 INFO - 'XPC_SERVICE_NAME': '0', 19:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:18:56 INFO - Cleaning up... 19:18:56 INFO - Return code: 0 19:18:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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', '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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:18: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:18: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 blobuploader==1.2.4 19:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:56 INFO - 'HOME': '/Users/cltbld', 19:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:56 INFO - 'LOGNAME': 'cltbld', 19:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:56 INFO - 'MOZ_NO_REMOTE': '1', 19:18:56 INFO - 'NO_EM_RESTART': '1', 19:18:56 INFO - 'PAGER': '/bin/cat', 19:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:56 INFO - 'PWD': '/builds/slave/test', 19:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:56 INFO - 'SHELL': '/bin/bash', 19:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:18:56 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:56 INFO - 'USER': 'cltbld', 19:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:56 INFO - 'XPC_FLAGS': '0x0', 19:18:56 INFO - 'XPC_SERVICE_NAME': '0', 19:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:56 INFO - Downloading/unpacking blobuploader==1.2.4 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:59 INFO - Downloading blobuploader-1.2.4.tar.gz 19:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:18:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:18:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:18:59 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 19:19:00 INFO - Downloading docopt-0.6.1.tar.gz 19:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:19:00 INFO - Installing collected packages: blobuploader, requests, docopt 19:19:00 INFO - Running setup.py install for blobuploader 19:19:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:19:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:19:00 INFO - Running setup.py install for requests 19:19:01 INFO - Running setup.py install for docopt 19:19:01 INFO - Successfully installed blobuploader requests docopt 19:19:01 INFO - Cleaning up... 19:19:01 INFO - Return code: 0 19:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:01 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:19:01 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 19:19:01 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 19:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:01 INFO - 'HOME': '/Users/cltbld', 19:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:01 INFO - 'LOGNAME': 'cltbld', 19:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:01 INFO - 'MOZ_NO_REMOTE': '1', 19:19:01 INFO - 'NO_EM_RESTART': '1', 19:19:01 INFO - 'PAGER': '/bin/cat', 19:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:01 INFO - 'PWD': '/builds/slave/test', 19:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:01 INFO - 'SHELL': '/bin/bash', 19:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:19:01 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:01 INFO - 'USER': 'cltbld', 19:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:01 INFO - 'XPC_FLAGS': '0x0', 19:19:01 INFO - 'XPC_SERVICE_NAME': '0', 19:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:01 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-DDg4so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:01 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-__EGIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:01 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-vbX6uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:01 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-pFMbjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:01 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-pl8U18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Pm_HGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-gJEqZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-NF3roC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-CXlqPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-NHJML0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-m4dVYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-QN9zBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-q31odZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:02 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8Rm2BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:03 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-a33xwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:03 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-paK0QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:03 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-FfZQ2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:19:03 INFO - Running setup.py install for manifestparser 19:19:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:19:03 INFO - Running setup.py install for mozcrash 19:19:03 INFO - Running setup.py install for mozdebug 19:19:03 INFO - Running setup.py install for mozdevice 19:19:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:19:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:19:03 INFO - Running setup.py install for mozfile 19:19:04 INFO - Running setup.py install for mozhttpd 19:19:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Running setup.py install for mozinfo 19:19:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Running setup.py install for mozInstall 19:19:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Running setup.py install for mozleak 19:19:04 INFO - Running setup.py install for mozlog 19:19:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Running setup.py install for moznetwork 19:19:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:19:04 INFO - Running setup.py install for mozprocess 19:19:05 INFO - Running setup.py install for mozprofile 19:19:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:19:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:19:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:19:05 INFO - Running setup.py install for mozrunner 19:19:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:19:05 INFO - Running setup.py install for mozscreenshot 19:19:05 INFO - Running setup.py install for moztest 19:19:05 INFO - Running setup.py install for mozversion 19:19:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:19:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:19:05 INFO - Cleaning up... 19:19:05 INFO - Return code: 0 19:19:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:05 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:19:05 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 19:19:05 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 19:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:05 INFO - 'HOME': '/Users/cltbld', 19:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:05 INFO - 'LOGNAME': 'cltbld', 19:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:05 INFO - 'MOZ_NO_REMOTE': '1', 19:19:05 INFO - 'NO_EM_RESTART': '1', 19:19:05 INFO - 'PAGER': '/bin/cat', 19:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:05 INFO - 'PWD': '/builds/slave/test', 19:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:05 INFO - 'SHELL': '/bin/bash', 19:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:19:05 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:05 INFO - 'USER': 'cltbld', 19:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:05 INFO - 'XPC_FLAGS': '0x0', 19:19:05 INFO - 'XPC_SERVICE_NAME': '0', 19:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-kDf1Vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:06 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)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-mHZj5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-ooQ4mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:06 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)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-VDcpwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-FqVG4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:06 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)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-phXjDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:06 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)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-4XSvWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:06 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)) 19:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-0AE6VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-WVL1Yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-rfGJhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-yf_EpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-MOTTYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-vY5sBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-l5VsJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-CakzX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Dd78xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:07 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)) 19:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-RF9Ds7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:07 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)) 19:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:07 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)) 19:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:19:07 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 19:19:07 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 19:19:07 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 19:19:07 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 19:19:07 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 19:19:07 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 19:19:10 INFO - Downloading blessings-1.6.tar.gz 19:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:19:10 INFO - Installing collected packages: blessings 19:19:10 INFO - Running setup.py install for blessings 19:19:10 INFO - Successfully installed blessings 19:19:10 INFO - Cleaning up... 19:19:10 INFO - Return code: 0 19:19:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:19:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:11 INFO - Reading from file tmpfile_stdout 19:19:11 INFO - Current package versions: 19:19:11 INFO - blessings == 1.6 19:19:11 INFO - blobuploader == 1.2.4 19:19:11 INFO - docopt == 0.6.1 19:19:11 INFO - functools32 == 3.2.3-2 19:19:11 INFO - jsonschema == 2.5.1 19:19:11 INFO - manifestparser == 1.1 19:19:11 INFO - mozInstall == 1.12 19:19:11 INFO - mozcrash == 0.17 19:19:11 INFO - mozdebug == 0.1 19:19:11 INFO - mozdevice == 0.48 19:19:11 INFO - mozfile == 1.2 19:19:11 INFO - mozhttpd == 0.7 19:19:11 INFO - mozinfo == 0.9 19:19:11 INFO - mozleak == 0.1 19:19:11 INFO - mozlog == 3.4 19:19:11 INFO - moznetwork == 0.27 19:19:11 INFO - mozprocess == 0.23 19:19:11 INFO - mozprofile == 0.28 19:19:11 INFO - mozrunner == 6.12 19:19:11 INFO - mozscreenshot == 0.1 19:19:11 INFO - mozsystemmonitor == 0.3 19:19:11 INFO - moztest == 0.7 19:19:11 INFO - mozversion == 1.4 19:19:11 INFO - psutil == 3.1.1 19:19:11 INFO - requests == 1.2.3 19:19:11 INFO - wsgiref == 0.1.2 19:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:11 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')]} 19:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:11 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')]} 19:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:11 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:19:11 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 19:19:11 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 19:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:11 INFO - 'HOME': '/Users/cltbld', 19:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:11 INFO - 'LOGNAME': 'cltbld', 19:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:11 INFO - 'MOZ_NO_REMOTE': '1', 19:19:11 INFO - 'NO_EM_RESTART': '1', 19:19:11 INFO - 'PAGER': '/bin/cat', 19:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:11 INFO - 'PWD': '/builds/slave/test', 19:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:11 INFO - 'SHELL': '/bin/bash', 19:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:19:11 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:11 INFO - 'USER': 'cltbld', 19:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:11 INFO - 'XPC_FLAGS': '0x0', 19:19:11 INFO - 'XPC_SERVICE_NAME': '0', 19:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 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)) 19:19:11 INFO - Cleaning up... 19:19:11 INFO - Return code: 0 19:19:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:19:11 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')]} 19:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:11 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')]} 19:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:11 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 0x10fa8d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96c0c95ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, '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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 19:19:11 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 19:19:11 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 19:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:11 INFO - 'HOME': '/Users/cltbld', 19:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:11 INFO - 'LOGNAME': 'cltbld', 19:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:11 INFO - 'MOZ_NO_REMOTE': '1', 19:19:11 INFO - 'NO_EM_RESTART': '1', 19:19:11 INFO - 'PAGER': '/bin/cat', 19:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:11 INFO - 'PWD': '/builds/slave/test', 19:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:11 INFO - 'SHELL': '/bin/bash', 19:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:19:11 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:11 INFO - 'USER': 'cltbld', 19:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:11 INFO - 'XPC_FLAGS': '0x0', 19:19:11 INFO - 'XPC_SERVICE_NAME': '0', 19:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:19:11 INFO - Cleaning up... 19:19:11 INFO - Return code: 0 19:19:11 INFO - Running post-action listener: _resource_record_post_action 19:19:11 INFO - Running post-action listener: _start_resource_monitoring 19:19:11 INFO - Starting resource monitoring. 19:19:11 INFO - [mozharness: 2016-10-09 02:19:11.806592Z] Finished create-virtualenv step (success) 19:19:11 INFO - [mozharness: 2016-10-09 02:19:11.807558Z] Running install step. 19:19:11 INFO - Running pre-action listener: _resource_record_pre_action 19:19:11 INFO - Running main action method: install 19:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:12 INFO - Reading from file tmpfile_stdout 19:19:12 INFO - Detecting whether we're running mozinstall >=1.0... 19:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:19:12 INFO - Reading from file tmpfile_stdout 19:19:12 INFO - Output received: 19:19:12 INFO - Usage: mozinstall [options] installer 19:19:12 INFO - Options: 19:19:12 INFO - -h, --help show this help message and exit 19:19:12 INFO - -d DEST, --destination=DEST 19:19:12 INFO - Directory to install application into. [default: 19:19:12 INFO - "/builds/slave/test"] 19:19:12 INFO - --app=APP Application being installed. [default: firefox] 19:19:12 INFO - mkdir: /builds/slave/test/build/application 19:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:19:46 INFO - Reading from file tmpfile_stdout 19:19:46 INFO - Output received: 19:19:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:19:46 INFO - Running post-action listener: _resource_record_post_action 19:19:46 INFO - [mozharness: 2016-10-09 02:19:46.698445Z] Finished install step (success) 19:19:46 INFO - [mozharness: 2016-10-09 02:19:46.698598Z] Running run-tests step. 19:19:46 INFO - Running pre-action listener: _resource_record_pre_action 19:19:46 INFO - Running main action method: run_tests 19:19:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:19:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:19:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:19:46 INFO - 2016-10-08 19:19:46.712 screenresolution[1646:9326] starting screenresolution argv=screenresolution get 19:19:46 INFO - 2016-10-08 19:19:46.715 screenresolution[1646:9326] Display 0: 1600x1200x32@60 19:19:46 INFO - 2016-10-08 19:19:46.720 screenresolution[1647:9328] starting screenresolution argv=screenresolution list 19:19:46 INFO - Available Modes on Display 0 19:19:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:19:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:19:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:19:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:19:46 INFO - Intel Iris: 19:19:46 INFO - Chipset Model: Intel Iris 19:19:46 INFO - Type: GPU 19:19:46 INFO - Bus: Built-In 19:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 19:19:46 INFO - Vendor: Intel (0x8086) 19:19:46 INFO - Device ID: 0x0a2e 19:19:46 INFO - Revision ID: 0x0009 19:19:46 INFO - Displays: 19:19:46 INFO - Display: 19:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:19:46 INFO - Display Serial Number: 1600x1200 60 19:19:46 INFO - Main Display: Yes 19:19:46 INFO - Mirror: Off 19:19:46 INFO - Online: Yes 19:19:46 INFO - Rotation: Supported 19:19:46 INFO - Return code: 0 19:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:19:46 INFO - Python 2.7.3 19:19:46 INFO - Return code: 0 19:19:46 INFO - grabbing minidump binary from tooltool 19:19: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')]} 19:19:46 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 0x10f8a1d30>, '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 0x10f6c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f899b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:19:46 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 19:19:46 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 19:19:46 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 19:19:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:19:47 INFO - Return code: 0 19:19:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:19:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:19:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:19:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:19:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--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 19:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0329 --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 19:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FTmKMkaJ5f/Render', 19:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:47 INFO - 'HOME': '/Users/cltbld', 19:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:47 INFO - 'LOGNAME': 'cltbld', 19:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:47 INFO - 'MOZ_NO_REMOTE': '1', 19:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:19:47 INFO - 'NO_EM_RESTART': '1', 19:19:47 INFO - 'PAGER': '/bin/cat', 19:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:47 INFO - 'PWD': '/builds/slave/test', 19:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:47 INFO - 'SHELL': '/bin/bash', 19:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pIWvQxQRZe/Listeners', 19:19:47 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 19:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:47 INFO - 'USER': 'cltbld', 19:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:47 INFO - 'XPC_FLAGS': '0x0', 19:19:47 INFO - 'XPC_SERVICE_NAME': '0', 19:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--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 19:19:47 INFO - mozversion application_buildid: 20161008164919 19:19:47 INFO - mozversion application_changeset: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 19:19:47 INFO - mozversion application_display_name: Nightly 19:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:19:47 INFO - mozversion application_name: Firefox 19:19:47 INFO - mozversion application_remotingname: firefox 19:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:19:47 INFO - mozversion application_vendor: Mozilla 19:19:47 INFO - mozversion application_version: 52.0a1 19:19:47 INFO - mozversion platform_buildid: 20161008164919 19:19:47 INFO - mozversion platform_changeset: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 19:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:19:47 INFO - mozversion platform_version: 52.0a1 19:19:47 INFO - using testdate: 1475979587 19:19:47 INFO - actual date: 1475979587 19:19:47 INFO - starting webserver on 'localhost:49277' 19:19:47 INFO - SUITE-START | Running 2 tests 19:19:47 INFO - TEST-START | dromaeo_css 19:19:47 INFO - operating with platform_type : mac_ 19:19:47 INFO - Initialising browser for dromaeo_css test... 19:19:47 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpLHMPFh/profile http://localhost:49277/getInfo.html) 19:19:49 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 19:19:49 INFO - PROCESS | 1657 | colorDepth:24 19:19:49 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 19:19:49 INFO - PROCESS | 1657 | __metrics 19:19:49 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:19:49 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:50 INFO - TEST-INFO | 1657: exit 0 19:19:50 INFO - Browser initialized. 19:19:50 INFO - Running cycle 1/1 for dromaeo_css test... 19:19:50 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpLHMPFh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:02 INFO - PROCESS | 1660 | RSS: Main: 234848256 19:20:02 INFO - PROCESS | 1660 | 19:20:53 INFO - PROCESS | 1660 | 1475979653416 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] 19:23:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 19:23:08 INFO - PROCESS | 1660 | RSS: Main: 417882112 19:23:08 INFO - PROCESS | 1660 | 19:26:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 19:26:11 INFO - PROCESS | 1660 | RSS: Main: 369016832 19:26:11 INFO - PROCESS | 1660 | 19:29:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 19:29:14 INFO - PROCESS | 1660 | RSS: Main: 339234816 19:29:14 INFO - PROCESS | 1660 | 19:32:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 19:32:17 INFO - PROCESS | 1660 | RSS: Main: 345612288 19:32:17 INFO - PROCESS | 1660 | 19:35:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 19:35:21 INFO - PROCESS | 1660 | RSS: Main: 329719808 19:35:21 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 19:38:25 INFO - PROCESS | 1660 | RSS: Main: 542617600 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | __start_tp_report 19:38:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:38:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:38:25 INFO - PROCESS | 1660 | |i|pagename|runs| 19:38:25 INFO - PROCESS | 1660 | |0;dojo.html;3352.7653064285496;3355.547001154845;3378.847951842167;3391.66083391661;3434.5706786651667;5405.459444058444;5516.089845175541;5564.693941833202;5652.717364131798;5689.2319536862515;4912.557869791718;5641.548676105912;5710.14347847822;5718.771249150029;5754.74103665335;5102.209157580581;5102.770375333101;5138.845834624967;5186.19613959835;5186.818461353853;5220.791168353261;5272.551833094191;5278.340207474066;5297.576193904479;5298.788048478056;5225.869353266161;5248.291480650139;5283.101872681654;5329.333833270841;5332.986732520809;5078.085944529983;5109.284700141983;5126.487351264881;5132.076226279269;5174.016936781999;5088.414832294259;5103.744812759347;5119.744012799382;5137.38351397831;5152.6650767699975;5608.635438696471;5614;5631;5649.442554565109;5712;5956.672383018932;6018.969905150446;6024.186734790791;6185.0413185955795;6189.473894718954;6120.659592487412;6128.044828652048;6191.752329906798;6243.125962365272;6244.781432649881;5690.5163061140265;5698.886022279573;5711.657300561979;5727.541796656257;5746.396628354005;6141.938580614226;6250.593711408744;6287.779927702509;6314.55798094129;6322.557420980487;5994.790182343597;6097.390260973956;6110.877782444326;6126.724297406628;6143.266246520345;5177.922331165036;6223.533235007392;6225.719842607094;6229.688515574203;6238.313785483593;5915.674637894957;6128.17269668598;6159.137720719103;6169.753209871555;6171.228596425447;6083.11794789753;6115.666295175977;6129.540284478593;6147.44672979434;6152.3540028297275;4892.461829198785;4926.187179115477;4928.01439712059;4952.182889823139;4962.280469331927;4411.250087485135;4412.955870441318;4419.491758447755;4441.800118994653;4469.173202957463;5537.833864984057;5608.747606357723;5677.432256774289;5752;5757.251557297608;6137.254412462224;6173;6179.474744646755;6187;6262.624242545462;5782.132680098019;5785.739641716133;5794.159846822187;5797.159411885253;5819.272590926134;5236.528712415901;5394.083005889008;5395.73021348931;5403.621746477787;5406.08096623575;6202.224721909762;6256.5307601930945;6258.092576576462;6279;6302.779402720883;6233.594816336924;6241.469475094486;6247.5939063960695;6255.96872015637;6256.562040657111;6194.566380353332;6233.532485063729;6247.125402443752;6254.21822272216;6286;5950.702464876826;6014.939850601438;6072.240969878765;6124.418180273045;6153.600015998945;6031.064603643036;6137.46891820379;6222.408871157233;6225.408586184305;6226.657533835682;6031.668258245839;6041.78853740117;6046.771267369806;6052.303985041776;6077.179580756541;5821.738021788945;5877.853053673693;5918.917978862516;6040.7583696651645;6057.273127224761;5768.567357448292;5815.912761308499;5818.679972601548;5838.2702162229725;5838.299404071538;5826.242588463472;5944.078667806324;5959.314678811992;5968.8806223876145;5985.341612422543;5133.3326667534475;5776.046952252831;5796.652200867961;5832.737526811219;5835.124731290341 19:38:25 INFO - PROCESS | 1660 | |1;ext.html;464.3800526297393;477.92831075338694;479.9680686523973;486.99678661956375;537.981170659027;39325.4101188482;41273.79363103184;41476.963075923115;41515.584844151585;42842.57157428425;42133.36799948003;42797.78601106998;42914;42919.92700182497;42964.92587685309;8843.557822108869;9094.86357704635;9315.161635452825;9620.326577139604;9634.084761947639;11128.721781955435;12364.752704945895;12372.81440778389;12461.81307280387;12471.563495277667;12256.754864902696;12281.938590307036;12291;12304.753904921896;12313.13808033438;8859.246964008067;9076.8638470423;9077.45535267886;12249.326287054208;12271.018318534496;8860.291176705849;8908.866367004435;11905.821412678817;12220.2667839929;12260.264384136892;41098.794506027574;41267.380989285186;41278.761637150936;41321;41339.37990930139;3240.9189770255934;3242.238074052596;3255.1048461672995;3320.7509436792097;3604.2611264690677;31292.843535782402;31385.84307078473;31435.371292574022;31819.727210911766;32355;3137;3155.7475401967786;3237.4172648923186;3268.7221586165206;3373.291608762163;495332.5233373846;496352;496475;497530.5123474396;501862;49451.752741236065;58822.70588647029;59091;59224.703876480344;59233.40766592279;61894.38105618976;61948;62087.37912620906;62107.6894615524;62130.0680489793;15690.215489225722;16240.67518649644;16249.59376015609;16261.105639189722;16261.723358729787;11391.373474458984;11734.471948762326;11735.882641173479;12543.498260069677;12760.80858787119;13345.466181352636;13715.862841371458;16401.671966560603;16461.83538164603;16573.41993030262;5235.450277720861;5560.443955604407;6207.689615519206;6483.837904052436;6504.707288172043;869.891263592051;949.9572519236651;1028.5114570579065;1046.5133712823574;1141.2068612314529;29862.701372986427;29946.55080173799;30512.237194070327;30657;30686.38627227443;1043.3896170740202;1058.7237439505125;1089.8583184186004;1135.7728454309172;1161.2509931094396;15025.924870375578;15471;15500.30248638814;15619.687606247811;15928.256859637975;165985;184538;185418;185521;185703;57358.7132064337;57779;57786.42213577894;57828.71085644629;57896;1223.8714934931882;1260.5840072776145;1353.4992052940474;1359.4290398033047;1379.4482207117233;28764.993225237016;29461.705382945896;30251.848740757036;31983.520247195844;32193;50595.98808023892;51429.971400572525;51514.22728658998;51654.48345516497;51664.74167629138;565267;612718;613533;613689;838112.809435949;42169;42497.78751106225;42781;42849.14301714011;43132.27470901129;46703.06593868036;47455.762721186176;47759.5224047769;48098;48111.75944120257;37944.62055379521;38748.6125138745;38868.416973744854;39205;40526;38787;39012.414813778385;39586;40110;40535.594644053184;38450.42324365081;38918;39207;39358.81923542298;39365.2126957465;31998;34472.82763586166;35106.473402899486;35393.646063540065;35517.467237991965;25816.741832582185;25820.967161313336;25856.35359115962;26419.339516512242;26466.603000955387 19:38:25 INFO - PROCESS | 1660 | |2;jquery.html;350.2679400053887;360.22012343276805;369.53254133521085;370.30937301931897;398.6113539299184;15336.923315383421;15538.456154034611;16323.591910202236;21285.255016074403;21392;19408;20141.39575812728;20287.289944851935;20668.793312066875;20875.895620521875;4892.119418504668;5660.5471562275025;5938.64368137911;5955.225820643311;5962;5786.363500014995;5862.618929769573;5924.88150236995;5934.525237980973;5944.732487038072;5568.777248910059;5821.27234095738;5866.090755932837;5872.0898260769645;5873.412658734135;5769.480746732793;5792.333881603628;5810.360860305363;5818.679972601505;5827.796027139051;5005.824796132154;5084.161113416282;5087.313212716273;5276.604254680914;5342.0651386007685;12799.424025918763;13063.281519516422;13076;14583.343749531297;24572.508549829083;2174.945626359338;2178.6405243134864;2321.454458202321;2637.5779875220064;2953.3797902440515;9982.650607228785;10231.232657550714;10689.465526723632;12604.432800523906;15616.297266622916;1702.0808763267944;1773.5300145461463;1780.5014595913165;1795.7485951966735;1839.5585059585737;113821.43089284583;119438;121907;123983;125565.99999999908;11168.720781980353;11195.832062519068;11206.327620342805;11408.315501069963;11612.361320127475;14393.136411815325;14410.927945360207;14421.134731916118;14444.638884027983;14447.255021545872;8121.984751906011;8404.907362042895;8417.831643367099;8515.914840851636;8555.443896146731;6778.559718501336;8344.958275208706;8375.8324833503;8378.53918034514;8546.444481108832;4322.200392927256;4434.711743736647;4458;4532.9320060199125;4571.771411429481;1553.4252326639216;1700.5153531243536;1809.4933418642802;1879.6710575649206;1886.5566591850904;755.5882044285877;851.8594431918788;881.2333270054964;982.8918818929913;1038.231708535678;20386.28647997313;21119.049642766113;21156.47108822292;21262;21273.78726212718;898.1063841477771;921.8432866412726;932.9673461428818;935.9859602105973;969.1955676788249;9484.525773711286;10229.897701022894;11197.49611267495;11220.831687524693;11222.55109795599;501992;508397;508968;590529;603961;84499.15500844913;92873.53563232141;116978.41510792561;127357;138846;975.5024937282037;1005.7887843552936;1011.7672935224939;1016.1464369929295;1028.7736697926593;18682.813171868107;18857.90571047136;18860.436171329766;19030.09699030086;19275.903620481808;38111.856644300715;46902.82742931454;48727.756361217966;50509.49490505048;52799.73600131975;75772;77203.61398192972;77253;78418.6079069601;78641;6978.337057979484;7349.154847192642;7353;7526.5860377679755;7675.53946763196;11028.062614677843;11125.443727813416;12878.484860605846;13402.26287554194;13646.385912634154;9846.950765246414;10945.069669078217;11231.719207019563;11315.886841131483;11611.709707257049;9637.662681806103;10146.695599131743;10254.846177307198;11442.599509017144;11500.769984600429;11226.270292430965;11278.041366483612;11803.645890623295;11819.231749936227;11972.940135299268;7342.192358840416;7440.255974402517;7502.219224684609;7553.32020118192;7717.652705628372;9754.561044752862;11046.558137674403;11542.538298467967;11670.649880503599;11717 19:38:25 INFO - PROCESS | 1660 | |3;mootools.html;1517.4916403004995;1537.4849425442474;1743.3462451580658;1858.8327050565447;1921.7213504041922;8384.413091083627;8893.866592001108;8915;8923.330750193729;8933.910660893389;7859.68561257549;8085.636146373413;8400;8643.178898004695;8692.56537173142;2336.521013192296;2371.2530552875874;2372.406898275431;2380.33350661815;2383.2731017039796;2060.2377120465376;2241.60771864923;2293.7018187635585;2311.05246848792;2331.7901388875;2314.2363020203293;2324.5699545584034;2343.6367363058753;2349;2354.1878052072007;2109.799569040939;2233.7207849018873;2342.6837376954063;2347.788699017097;2349.729781075164;2308.134449581407;2313.9537209255723;2318.5362927414417;2321.883905804703;2326.720793504773;8728.51993140377;8735.43219690718;8853.867191992125;8873.06832782555;8891.02198758136;5129.846104616868;5310.654807437504;5322;5322.387925388591;5322.787088516493;3460;3538.168530395355;3686.4838922550866;3694.261147770457;3694.501242332278;1043.2227990147312;1075.4568942683973;1080.7946490166844;1088.2491081154058;1088.7985722641338;11500.022498087672;11581.305121692725;11605.187636865507;11614.36121013344;11634.941825290904;4507.143642707875;4566.543345665407;4772.069446457907;4774.021325628238;4786.353842231324;9860.359076659994;9881.80236395268;9887.802243955224;9890.20878329732;9895.554700038516;5331.86670333237;5333.040052790535;5336.519713225828;5340.786368545215;5344.839654810362;4345.217860785089;4432.889177770581;4510.391097201901;4808.158572249842;4872.9512704873205;3808.7524310919703;3829.9617003830163;3930.194310166409;3942.448057271984;3979.542352629485;1254.8243245945575;1265.936703164838;1270.6950331920273;1271.726578785547;1274.4010315151863;808.5270116981632;820.9055958564722;836.3482122807528;890.2210565754964;946.5976959792199;5433.646812957145;5828.88342233153;5946.643201407929;5947.583669143119;6062.787802426894;774.0788461730635;911.4485736129546;924.4915296586851;935.7052528453515;960.4333443268505;3814.809259537012;3824.330742120118;3851.730378873452;3917.177392747555;3969.702272329587;10289.125424339016;10742.301750386356;10757.9462102689;10765.892341076646;10769.407682577534;10237.385756854612;10569;10581.206409519316;10708.1433485321;10709.196810239264;8023.6790528378215;8261.215184557457;8264.545450000187;8267.131951145044;8273.048599411144;5620.915686264628;5800.854978625568;5824.184614154106;5999.3100793407575;6172.598781079216;9380.249580033444;10050;10062.195024397884;10093.343932644355;10099.89900101019;10297.43364114951;10315.226358023028;10315.432651204255;10317.432541210306;10324.587016519255;6930.7227710891;7132.679029443584;7158.964205178941;7453.962730186315;7597.050368703912;7985.560794156145;8144.511329320259;8242.423030387816;8420.705275315335;8466.915330846612;7421.666025028779;7544.132424771222;7557.244275572399;7602.315791578784;7684.231576842271;7276.96361518189;7329.083864516935;7359.411247100326;7435.330820226206;7539.019927409621;6815.079964204769;6960.37356637905;6969.512134150561;6971.65141742921;6974.546654467444;4869.293952376956;4890.24201248807;4981.750912454435;5028.094942910165;5068.721220332917;4867.14824905633;4968.22992436173;5050.696958182521;5121.795128194831;5176.275321455075 19:38:25 INFO - PROCESS | 1660 | |4;prototype.html;295.22208979339433;296.9792114551981;304.4854196408069;307.4511996086985;312.9812211267323;4561.133384656913;5162.225666150075;5273.446288139748;5279.260903473517;5292.894142117165;4475.440569928759;4687.015726697389;4890.6087512998965;5032.144535428976;5087.956968821392;3768;4025.939610905839;4027.5972402759635;4045.656119229862;4065;3655.1775850433705;3798.65812076913;4015.277250094982;4020.7989600520004;4043.2317859606583;3767.698584113264;3980.7412518186225;4007.138465229975;4011.3782363733667;4045.5347635022054;3705.8882335299436;3964.7026473014353;4002.7598344099447;4026.295398305285;4026.7382620129597;2889.8844046238123;3472.791632502058;3484.8083355415442;3487.720982321408;3502.4045912194733;4617.561331673485;4845.757712114416;4885.022995400898;4982.128127577659;5000.074926197693;4327.956720432787;4462.062966776981;4496.190685676577;4506.5493450655;4511.6165125964335;4843.975780121077;4892.3884514435695;5472.753726082336;5499.945000550023;5512.558995280408;3767.095896984704;3781;3785.7696248719276;3787.9053023674355;3793.3740932746064;5378.650387724785;5624.6625202487985;5656.519195868315;5697.03150464422;5704.657720536781;3789.488419063446;3798.848046078126;3801.41503777434;3801.6198380161763;3815.8855234343014;3498.755087143926;3677.3013127505687;3854.4603755474254;3858.9807050964564;3879.689624830063;4224.809883555247;4487.259602165672;4505.211587972091;4508.594226519629;4589.5181005994555;4541.046380260116;4781.42622885256;5444.346678398539;5457.617966742369;5518.771518661548;1229.416027386984;1283.7355205122947;1307.7907534794388;1328.6182370334743;1343.3350491506767;871.3769654696922;921.824853277875;935.2284365398573;943.3679434778714;948.5778828421425;5166.819161329336;5182;5203.453637368098;5214.357477394685;5229.294045303911;5327.307450031549;5517.15866328841;5563.554915606742;5602.635828671205;5654.031633392502;4317.568243175658;5132.769025393866;5179.870503237374;5207.739613019409;5216.843494695166;2580.87095645217;2646.8676566171616;2834.2286071205494;2906.0648744767514;2912.577676236976;5464.836054918359;5641.576881733887;5645.520130788929;5685.715714214272;5896.7641294348605;5821.592488525763;5851.385604511465;5854.648721076749;5864.149698293723;5868.970655146697;4950.752462376939;5301.9057472299;5832.591718579659;5898.380670029671;5899.882002360015;4980.8754781130765;5378.757955891916;5458.399576046552;5597.412271711411;5674.573832410726;5610;5610.689693081102;5621.381648018633;5624.987502249634;5625.128105143709;4987.576056035277;5126.641440018324;5573.913086948006;5890.408220383018;5935.910961335497;3701.5002974598083;3760.172761992357;3790.521696538297;3831.2912111258393;3837.642063194705;4980.726060066586;5714.9142762857755;5723.742431590672;5728.971355143198;5744.5011420032415;5006.3992320921725;5234.18870075139;5332.78668853257;5498.560115190855;5575.944240557543;4431.82272709088;4572.588467037983;5451.48210919962;5462.289902312674;5464.617476776588;4424.296645791329;5151.768170432414;5301.946980530145;5351.892962140658;5367.570594352521;5325.068113080118;5514.393416724238;5515.731381782132;5518.172274158908;5567.498925096761;4846.369971903771;4953.900921981468;5412.377576578744;5437.755301011543;5501.917471237854 19:38:25 INFO - PROCESS | 1660 | |5;yui.html;694.2328726756932;700.7442283566496;758.5562445969111;787.8694074003809;813.1055838577565;15774.605634859134;16171.43399981001;16176.35294588215;16194.271257793398;16202.594935126614;14517.20155391453;14622.341994610242;14631.561053168421;14647.853521464782;14649.560513184622;676.2662511175381;699.4369532526314;699.8040548646387;827.7392621324263;845.5687599324327;850.1838235294125;859.5358506406533;860.5697151424288;864.2178828160494;864.5288317866746;907.7912080221553;909.172652885874;909.6770646420524;909.7543663210929;910.0944560162615;717.7990162754437;901.9188273055456;902.4540153207281;903.7921278106006;903.8824952756165;544.8528897197774;653.2944420026407;667.9398854103106;674.652553934724;676.1142902797351;15359.4624188154;15371;15392.615184620363;15405;15429.614259643486;13799.2410417427;14093.78859317111;14284.214368209745;14355.71288574233;14502.274886255645;12886.935565322114;12973.091883568153;13058.542950996669;13228.206307621573;13327.933360333232;4289.120730250323;4289.656827453796;4388.100439409914;4470.977645111786;4479.059397526524;121061;123522.38238808748;124825;125034;125910;10200.846987295197;11075.280106793032;11204.047655949336;11221.326720396803;11538.76922461546;20431.79568204329;20973.26593569248;21178.258760943292;21208;21221.575568488544;7603.5553244883295;7725.150233474314;7839.451238413257;7874.291313781787;7918.2477664621765;4162.708610397243;4198;4279.764612946318;4366.323219900921;4397.516273209944;3424.4692072728767;3503.4219353806848;3547.255076433926;3548.929021419557;3555.1645363339594;642.1491523731075;642.6529673976;644.2880616918238;659.5020759326677;659.8548319369731;555.910415585449;600.6006006006033;607.9483243924316;609.7256234694405;623.1338439569003;8509.914900850912;9067.68263110788;9078;9260.7684807879;9495.952520237355;513.9871503212449;549.884524249912;573.7274794472676;620.3424370167637;623.4046485606235;4279.486461624625;4288.333474878934;4480.171168333869;4516.345129956204;4673.556012178838;153349.26555141032;155499;157186.6422003671;158195;160158;91103.61204849393;133884;136476.65751863396;137982;142831;118671.40664296623;120274.19588706351;121295.18057228973;121340;124210;8481.491110533389;8608.741737747878;8644.524551149747;8722.694705685271;8873.157050080232;65306.67346663236;65438.820147481565;66102.67794644176;67526;69482.65258673675;56884.715576421855;62579;74186.51626967538;80188;97155;13307.800382994457;13360.064795464614;13426.664333391744;13593.932030339785;13842.169469831842;12929.418176181905;14123.858761412665;14628.853711462749;15077.321520531383;15238.619034524225;13633.590992270249;13776.311184440938;13923.955703322088;14111.506097286545;14142.292885355486;11295.3787541686;13370;13499.46002159942;13626.659333516742;13629.795553066513;12818.487260509475;13347.599572012856;13374;13414.262215577846;13551.932240338736;11180.385078820744;11244.55021799119;11453.541858325574;11884.643460696192;11943.462544185359;8175;9880.505974701093;10230.744231394274;11525.538978440769;11587.478563464496 19:38:25 INFO - PROCESS | 1660 | __end_tp_report 19:38:25 INFO - PROCESS | 1660 | __start_cc_report 19:38:25 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2304 19:38:25 INFO - PROCESS | 1660 | __end_cc_report 19:38:25 INFO - PROCESS | 1660 | __startTimestamp1475980705288__endTimestamp 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:38:25 INFO - PROCESS | 1660 | Number of tests: 6 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5680.43 Median:5821.74 stddev:618.43 (10.6%) stddev-sans-first:592.76 19:38:25 INFO - PROCESS | 1660 | Values: 3352.8 3355.5 3378.8 3391.7 3434.6 5405.5 5516.1 5564.7 5652.7 5689.2 4912.6 5641.5 5710.1 5718.8 5754.7 5102.2 5102.8 5138.8 5186.2 5186.8 5220.8 5272.6 5278.3 5297.6 5298.8 5225.9 5248.3 5283.1 5329.3 5333.0 5078.1 5109.3 5126.5 5132.1 5174.0 5088.4 5103.7 5119.7 5137.4 5152.7 5608.6 5614.0 5631.0 5649.4 5712.0 5956.7 6019.0 6024.2 6185.0 6189.5 6120.7 6128.0 6191.8 6243.1 6244.8 5690.5 5698.9 5711.7 5727.5 5746.4 6141.9 6250.6 6287.8 6314.6 6322.6 5994.8 6097.4 6110.9 6126.7 6143.3 5177.9 6223.5 6225.7 6229.7 6238.3 5915.7 6128.2 6159.1 6169.8 6171.2 6083.1 6115.7 6129.5 6147.4 6152.4 4892.5 4926.2 4928.0 4952.2 4962.3 4411.3 4413.0 4419.5 4441.8 4469.2 5537.8 5608.7 5677.4 5752.0 5757.3 6137.3 6173.0 6179.5 6187.0 6262.6 5782.1 5785.7 5794.2 5797.2 5819.3 5236.5 5394.1 5395.7 5403.6 5406.1 6202.2 6256.5 6258.1 6279.0 6302.8 6233.6 6241.5 6247.6 6256.0 6256.6 6194.6 6233.5 6247.1 6254.2 6286.0 5950.7 6014.9 6072.2 6124.4 6153.6 6031.1 6137.5 6222.4 6225.4 6226.7 6031.7 6041.8 6046.8 6052.3 6077.2 5821.7 5877.9 5918.9 6040.8 6057.3 5768.6 5815.9 5818.7 5838.3 5838.3 5826.2 5944.1 5959.3 5968.9 5985.3 5133.3 5776.0 5796.7 5832.7 5835.1 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:60352.21 Median:38767.81 stddev:130832.66 (337.5%) stddev-sans-first:131122.41 19:38:25 INFO - PROCESS | 1660 | Values: 464.4 477.9 480.0 487.0 538.0 39325.4 41273.8 41477.0 41515.6 42842.6 42133.4 42797.8 42914.0 42919.9 42964.9 8843.6 9094.9 9315.2 9620.3 9634.1 11128.7 12364.8 12372.8 12461.8 12471.6 12256.8 12281.9 12291.0 12304.8 12313.1 8859.2 9076.9 9077.5 12249.3 12271.0 8860.3 8908.9 11905.8 12220.3 12260.3 41098.8 41267.4 41278.8 41321.0 41339.4 3240.9 3242.2 3255.1 3320.8 3604.3 31292.8 31385.8 31435.4 31819.7 32355.0 3137.0 3155.7 3237.4 3268.7 3373.3 495332.5 496352.0 496475.0 497530.5 501862.0 49451.8 58822.7 59091.0 59224.7 59233.4 61894.4 61948.0 62087.4 62107.7 62130.1 15690.2 16240.7 16249.6 16261.1 16261.7 11391.4 11734.5 11735.9 12543.5 12760.8 13345.5 13715.9 16401.7 16461.8 16573.4 5235.5 5560.4 6207.7 6483.8 6504.7 869.9 950.0 1028.5 1046.5 1141.2 29862.7 29946.6 30512.2 30657.0 30686.4 1043.4 1058.7 1089.9 1135.8 1161.3 15025.9 15471.0 15500.3 15619.7 15928.3 165985.0 184538.0 185418.0 185521.0 185703.0 57358.7 57779.0 57786.4 57828.7 57896.0 1223.9 1260.6 1353.5 1359.4 1379.4 28765.0 29461.7 30251.8 31983.5 32193.0 50596.0 51430.0 51514.2 51654.5 51664.7 565267.0 612718.0 613533.0 613689.0 838112.8 42169.0 42497.8 42781.0 42849.1 43132.3 46703.1 47455.8 47759.5 48098.0 48111.8 37944.6 38748.6 38868.4 39205.0 40526.0 38787.0 39012.4 39586.0 40110.0 40535.6 38450.4 38918.0 39207.0 39358.8 39365.2 31998.0 34472.8 35106.5 35393.6 35517.5 25816.7 25821.0 25856.4 26419.3 26466.6 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:32581.47 Median:13605.04 stddev:91203.71 (670.4%) stddev-sans-first:91427.46 19:38:25 INFO - PROCESS | 1660 | Values: 350.3 360.2 369.5 370.3 398.6 15336.9 15538.5 16323.6 21285.3 21392.0 19408.0 20141.4 20287.3 20668.8 20875.9 4892.1 5660.5 5938.6 5955.2 5962.0 5786.4 5862.6 5924.9 5934.5 5944.7 5568.8 5821.3 5866.1 5872.1 5873.4 5769.5 5792.3 5810.4 5818.7 5827.8 5005.8 5084.2 5087.3 5276.6 5342.1 12799.4 13063.3 13076.0 14583.3 24572.5 2174.9 2178.6 2321.5 2637.6 2953.4 9982.7 10231.2 10689.5 12604.4 15616.3 1702.1 1773.5 1780.5 1795.7 1839.6 113821.4 119438.0 121907.0 123983.0 125566.0 11168.7 11195.8 11206.3 11408.3 11612.4 14393.1 14410.9 14421.1 14444.6 14447.3 8122.0 8404.9 8417.8 8515.9 8555.4 6778.6 8345.0 8375.8 8378.5 8546.4 4322.2 4434.7 4458.0 4532.9 4571.8 1553.4 1700.5 1809.5 1879.7 1886.6 755.6 851.9 881.2 982.9 1038.2 20386.3 21119.0 21156.5 21262.0 21273.8 898.1 921.8 933.0 936.0 969.2 9484.5 10229.9 11197.5 11220.8 11222.6 501992.0 508397.0 508968.0 590529.0 603961.0 84499.2 92873.5 116978.4 127357.0 138846.0 975.5 1005.8 1011.8 1016.1 1028.8 18682.8 18857.9 18860.4 19030.1 19275.9 38111.9 46902.8 48727.8 50509.5 52799.7 75772.0 77203.6 77253.0 78418.6 78641.0 6978.3 7349.2 7353.0 7526.6 7675.5 11028.1 11125.4 12878.5 13402.3 13646.4 9847.0 10945.1 11231.7 11315.9 11611.7 9637.7 10146.7 10254.8 11442.6 11500.8 11226.3 11278.0 11803.6 11819.2 11972.9 7342.2 7440.3 7502.2 7553.3 7717.7 9754.6 11046.6 11542.5 11670.6 11717.0 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5691.83 Median:4881.60 stddev:3193.68 (65.4%) stddev-sans-first:3187.23 19:38:25 INFO - PROCESS | 1660 | Values: 1517.5 1537.5 1743.3 1858.8 1921.7 8384.4 8893.9 8915.0 8923.3 8933.9 7859.7 8085.6 8400.0 8643.2 8692.6 2336.5 2371.3 2372.4 2380.3 2383.3 2060.2 2241.6 2293.7 2311.1 2331.8 2314.2 2324.6 2343.6 2349.0 2354.2 2109.8 2233.7 2342.7 2347.8 2349.7 2308.1 2314.0 2318.5 2321.9 2326.7 8728.5 8735.4 8853.9 8873.1 8891.0 5129.8 5310.7 5322.0 5322.4 5322.8 3460.0 3538.2 3686.5 3694.3 3694.5 1043.2 1075.5 1080.8 1088.2 1088.8 11500.0 11581.3 11605.2 11614.4 11634.9 4507.1 4566.5 4772.1 4774.0 4786.4 9860.4 9881.8 9887.8 9890.2 9895.6 5331.9 5333.0 5336.5 5340.8 5344.8 4345.2 4432.9 4510.4 4808.2 4873.0 3808.8 3830.0 3930.2 3942.4 3979.5 1254.8 1265.9 1270.7 1271.7 1274.4 808.5 820.9 836.3 890.2 946.6 5433.6 5828.9 5946.6 5947.6 6062.8 774.1 911.4 924.5 935.7 960.4 3814.8 3824.3 3851.7 3917.2 3969.7 10289.1 10742.3 10757.9 10765.9 10769.4 10237.4 10569.0 10581.2 10708.1 10709.2 8023.7 8261.2 8264.5 8267.1 8273.0 5620.9 5800.9 5824.2 5999.3 6172.6 9380.2 10050.0 10062.2 10093.3 10099.9 10297.4 10315.2 10315.4 10317.4 10324.6 6930.7 7132.7 7159.0 7454.0 7597.1 7985.6 8144.5 8242.4 8420.7 8466.9 7421.7 7544.1 7557.2 7602.3 7684.2 7277.0 7329.1 7359.4 7435.3 7539.0 6815.1 6960.4 6969.5 6971.7 6974.5 4869.3 4890.2 4981.8 5028.1 5068.7 4867.1 4968.2 5050.7 5121.8 5176.3 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4459.38 Median:4984.85 stddev:1357.61 (27.2%) stddev-sans-first:1324.95 19:38:25 INFO - PROCESS | 1660 | Values: 295.2 297.0 304.5 307.5 313.0 4561.1 5162.2 5273.4 5279.3 5292.9 4475.4 4687.0 4890.6 5032.1 5088.0 3768.0 4025.9 4027.6 4045.7 4065.0 3655.2 3798.7 4015.3 4020.8 4043.2 3767.7 3980.7 4007.1 4011.4 4045.5 3705.9 3964.7 4002.8 4026.3 4026.7 2889.9 3472.8 3484.8 3487.7 3502.4 4617.6 4845.8 4885.0 4982.1 5000.1 4328.0 4462.1 4496.2 4506.5 4511.6 4844.0 4892.4 5472.8 5499.9 5512.6 3767.1 3781.0 3785.8 3787.9 3793.4 5378.7 5624.7 5656.5 5697.0 5704.7 3789.5 3798.8 3801.4 3801.6 3815.9 3498.8 3677.3 3854.5 3859.0 3879.7 4224.8 4487.3 4505.2 4508.6 4589.5 4541.0 4781.4 5444.3 5457.6 5518.8 1229.4 1283.7 1307.8 1328.6 1343.3 871.4 921.8 935.2 943.4 948.6 5166.8 5182.0 5203.5 5214.4 5229.3 5327.3 5517.2 5563.6 5602.6 5654.0 4317.6 5132.8 5179.9 5207.7 5216.8 2580.9 2646.9 2834.2 2906.1 2912.6 5464.8 5641.6 5645.5 5685.7 5896.8 5821.6 5851.4 5854.6 5864.1 5869.0 4950.8 5301.9 5832.6 5898.4 5899.9 4980.9 5378.8 5458.4 5597.4 5674.6 5610.0 5610.7 5621.4 5625.0 5625.1 4987.6 5126.6 5573.9 5890.4 5935.9 3701.5 3760.2 3790.5 3831.3 3837.6 4980.7 5714.9 5723.7 5729.0 5744.5 5006.4 5234.2 5332.8 5498.6 5575.9 4431.8 4572.6 5451.5 5462.3 5464.6 4424.3 5151.8 5301.9 5351.9 5367.6 5325.1 5514.4 5515.7 5518.2 5567.5 4846.4 4953.9 5412.4 5437.8 5501.9 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:25438.79 Median:3552.05 stddev:41459.29 (1167.2%) stddev-sans-first:41533.97 19:38:25 INFO - PROCESS | 1660 | Values: 694.2 700.7 758.6 787.9 813.1 15774.6 16171.4 16176.4 16194.3 16202.6 14517.2 14622.3 14631.6 14647.9 14649.6 676.3 699.4 699.8 827.7 845.6 850.2 859.5 860.6 864.2 864.5 907.8 909.2 909.7 909.8 910.1 717.8 901.9 902.5 903.8 903.9 544.9 653.3 667.9 674.7 676.1 15359.5 15371.0 15392.6 15405.0 15429.6 13799.2 14093.8 14284.2 14355.7 14502.3 12886.9 12973.1 13058.5 13228.2 13327.9 4289.1 4289.7 4388.1 4471.0 4479.1 121061.0 123522.4 124825.0 125034.0 125910.0 10200.8 11075.3 11204.0 11221.3 11538.8 20431.8 20973.3 21178.3 21208.0 21221.6 7603.6 7725.2 7839.5 7874.3 7918.2 4162.7 4198.0 4279.8 4366.3 4397.5 3424.5 3503.4 3547.3 3548.9 3555.2 642.1 642.7 644.3 659.5 659.9 555.9 600.6 607.9 609.7 623.1 8509.9 9067.7 9078.0 9260.8 9496.0 514.0 549.9 573.7 620.3 623.4 4279.5 4288.3 4480.2 4516.3 4673.6 153349.3 155499.0 157186.6 158195.0 160158.0 91103.6 133884.0 136476.7 137982.0 142831.0 118671.4 120274.2 121295.2 121340.0 124210.0 8481.5 8608.7 8644.5 8722.7 8873.2 65306.7 65438.8 66102.7 67526.0 69482.7 56884.7 62579.0 74186.5 80188.0 97155.0 13307.8 13360.1 13426.7 13593.9 13842.2 12929.4 14123.9 14628.9 15077.3 15238.6 13633.6 13776.3 13924.0 14111.5 14142.3 11295.4 13370.0 13499.5 13626.7 13629.8 12818.5 13347.6 13374.0 13414.3 13551.9 11180.4 11244.6 11453.5 11884.6 11943.5 8175.0 9880.5 10230.7 11525.5 11587.5 19:38:25 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:38:25 INFO - PROCESS | 1660 | 19:38:25 INFO - TEST-INFO | 1660: exit 0 19:38:25 INFO - TEST-OK | dromaeo_css | took 1118648ms 19:38:25 INFO - TEST-START | kraken 19:38:25 INFO - operating with platform_type : mac_ 19:38:25 INFO - Initialising browser for kraken test... 19:38:25 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmp_SidVs/profile http://localhost:49277/getInfo.html) 19:38:28 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 19:38:28 INFO - PROCESS | 1667 | colorDepth:24 19:38:28 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 19:38:28 INFO - PROCESS | 1667 | __metrics 19:38:28 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:38:28 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:38:29 INFO - TEST-INFO | 1667: exit 0 19:38:29 INFO - Browser initialized. 19:38:29 INFO - Running cycle 1/1 for kraken test... 19:38:29 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmp_SidVs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:38:41 INFO - PROCESS | 1668 | RSS: Main: 234840064 19:38:41 INFO - PROCESS | 1668 | 19:38:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 19:38:44 INFO - PROCESS | 1668 | RSS: Main: 293531648 19:38:44 INFO - PROCESS | 1668 | 19:38:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 19:38:48 INFO - PROCESS | 1668 | RSS: Main: 293580800 19:38:48 INFO - PROCESS | 1668 | 19:38:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 19:38:52 INFO - PROCESS | 1668 | RSS: Main: 381325312 19:38:52 INFO - PROCESS | 1668 | 19:38:54 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 19:38:54 INFO - PROCESS | 1668 | RSS: Main: 287862784 19:38:54 INFO - PROCESS | 1668 | 19:38:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:38:57 INFO - PROCESS | 1668 | RSS: Main: 289992704 19:38:57 INFO - PROCESS | 1668 | 19:39:00 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 19:39:00 INFO - PROCESS | 1668 | RSS: Main: 328245248 19:39:00 INFO - PROCESS | 1668 | 19:39:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 19:39:04 INFO - PROCESS | 1668 | RSS: Main: 327925760 19:39:04 INFO - PROCESS | 1668 | 19:39:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 19:39:07 INFO - PROCESS | 1668 | RSS: Main: 326291456 19:39:07 INFO - PROCESS | 1668 | 19:39:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:39:09 INFO - PROCESS | 1668 | RSS: Main: 301858816 19:39:09 INFO - PROCESS | 1668 | 19:39:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:39:12 INFO - PROCESS | 1668 | RSS: Main: 303714304 19:39:12 INFO - PROCESS | 1668 | 19:39:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:39:15 INFO - PROCESS | 1668 | RSS: Main: 305553408 19:39:15 INFO - PROCESS | 1668 | 19:39:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:39:19 INFO - PROCESS | 1668 | RSS: Main: 318648320 19:39:19 INFO - PROCESS | 1668 | 19:39:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:39:22 INFO - PROCESS | 1668 | RSS: Main: 317931520 19:39:22 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 19:39:24 INFO - PROCESS | 1668 | RSS: Main: 362024960 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | __start_tp_report 19:39:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 19:39:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 19:39:24 INFO - PROCESS | 1668 | |i|pagename|runs| 19:39:24 INFO - PROCESS | 1668 | |0;ai-astar;112;113;113;113;113;112;112;114;112;113 19:39:24 INFO - PROCESS | 1668 | |1;audio-beat-detection;147;110;130;179;141;113;111;115;144;112 19:39:24 INFO - PROCESS | 1668 | |2;audio-dft;183;181;185;182;186;181;183;182;177;179 19:39:24 INFO - PROCESS | 1668 | |3;audio-fft;79;59;59;86;71;77;67;58;84;59 19:39:24 INFO - PROCESS | 1668 | |4;audio-oscillator;87;73;95;72;67;68;92;67;68;90 19:39:24 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;88;86;89;86;87;88;89;87;90;87 19:39:24 INFO - PROCESS | 1668 | |6;imaging-darkroom;94;93;110;128;94;93;94;95;93;95 19:39:24 INFO - PROCESS | 1668 | |7;imaging-desaturate;83;87;83;83;84;83;83;84;83;81 19:39:24 INFO - PROCESS | 1668 | |8;json-parse-financial;52;54;53;53;55;56;56;56;55;55 19:39:24 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;74;79;72;72;72;69;69;71;69;71 19:39:24 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;89;83;86;77;95;102;96;86;86;87 19:39:24 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;135;146;162;159;153;139;168;148;209;179 19:39:24 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;174;174;170;174;173;173;173;172;173;172 19:39:24 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;67;69;66;69;69;69;68;68;69;81 19:39:24 INFO - PROCESS | 1668 | __end_tp_report 19:39:24 INFO - PROCESS | 1668 | __start_cc_report 19:39:24 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2019 19:39:24 INFO - PROCESS | 1668 | __end_cc_report 19:39:24 INFO - PROCESS | 1668 | __startTimestamp1475980764744__endTimestamp 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | ------- Summary: start ------- 19:39:24 INFO - PROCESS | 1668 | Number of tests: 14 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 19:39:24 INFO - PROCESS | 1668 | Values: 112.0 113.0 113.0 113.0 113.0 112.0 112.0 114.0 112.0 113.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:135.50 stddev:22.62 (16.7%) stddev-sans-first:23.16 19:39:24 INFO - PROCESS | 1668 | Values: 147.0 110.0 130.0 179.0 141.0 113.0 111.0 115.0 144.0 112.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:181.90 Median:182.50 stddev:2.64 (1.4%) stddev-sans-first:2.77 19:39:24 INFO - PROCESS | 1668 | Values: 183.0 181.0 185.0 182.0 186.0 181.0 183.0 182.0 177.0 179.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:69.90 Median:74.00 stddev:11.05 (14.9%) stddev-sans-first:11.22 19:39:24 INFO - PROCESS | 1668 | Values: 79.0 59.0 59.0 86.0 71.0 77.0 67.0 58.0 84.0 59.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.00 stddev:11.61 (14.5%) stddev-sans-first:11.84 19:39:24 INFO - PROCESS | 1668 | Values: 87.0 73.0 95.0 72.0 67.0 68.0 92.0 67.0 68.0 90.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:1.34 (1.5%) stddev-sans-first:1.41 19:39:24 INFO - PROCESS | 1668 | Values: 88.0 86.0 89.0 86.0 87.0 88.0 89.0 87.0 90.0 87.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:98.90 Median:94.00 stddev:11.44 (12.2%) stddev-sans-first:11.99 19:39:24 INFO - PROCESS | 1668 | Values: 94.0 93.0 110.0 128.0 94.0 93.0 94.0 95.0 93.0 95.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:83.40 Median:83.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 19:39:24 INFO - PROCESS | 1668 | Values: 83.0 87.0 83.0 83.0 84.0 83.0 83.0 84.0 83.0 81.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.43 (2.6%) stddev-sans-first:1.20 19:39:24 INFO - PROCESS | 1668 | Values: 52.0 54.0 53.0 53.0 55.0 56.0 56.0 56.0 55.0 55.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:3.01 (4.2%) stddev-sans-first:3.09 19:39:24 INFO - PROCESS | 1668 | Values: 74.0 79.0 72.0 72.0 72.0 69.0 69.0 71.0 69.0 71.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:86.50 stddev:7.18 (8.3%) stddev-sans-first:7.62 19:39:24 INFO - PROCESS | 1668 | Values: 89.0 83.0 86.0 77.0 95.0 102.0 96.0 86.0 86.0 87.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:159.80 Median:160.50 stddev:21.82 (13.6%) stddev-sans-first:21.22 19:39:24 INFO - PROCESS | 1668 | Values: 135.0 146.0 162.0 159.0 153.0 139.0 168.0 148.0 209.0 179.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:173.00 stddev:1.23 (0.7%) stddev-sans-first:1.22 19:39:24 INFO - PROCESS | 1668 | Values: 174.0 174.0 170.0 174.0 173.0 173.0 173.0 172.0 173.0 172.0 19:39:24 INFO - PROCESS | 1668 | 19:39:24 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:4.17 (6.0%) stddev-sans-first:4.32 19:39:24 INFO - PROCESS | 1668 | Values: 67.0 69.0 66.0 69.0 69.0 69.0 68.0 68.0 69.0 81.0 19:39:24 INFO - PROCESS | 1668 | -------- Summary: end -------- 19:39:24 INFO - PROCESS | 1668 | 19:39:25 INFO - TEST-INFO | 1668: exit 0 19:39:25 INFO - TEST-OK | kraken | took 59730ms 19:39:25 INFO - SUITE-END | took 1178s 19:39:25 INFO - Completed test suite (00:19:38) 19:39:25 INFO - javascript benchmark 19:39:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.7653064285496, 3355.547001154845, 3378.847951842167, 3391.66083391661, 3434.5706786651667, 5405.459444058444, 5516.089845175541, 5564.693941833202, 5652.717364131798, 5689.2319536862515, 4912.557869791718, 5641.548676105912, 5710.14347847822, 5718.771249150029, 5754.74103665335, 5102.209157580581, 5102.770375333101, 5138.845834624967, 5186.19613959835, 5186.818461353853, 5220.791168353261, 5272.551833094191, 5278.340207474066, 5297.576193904479, 5298.788048478056, 5225.869353266161, 5248.291480650139, 5283.101872681654, 5329.333833270841, 5332.986732520809, 5078.085944529983, 5109.284700141983, 5126.487351264881, 5132.076226279269, 5174.016936781999, 5088.414832294259, 5103.744812759347, 5119.744012799382, 5137.38351397831, 5152.6650767699975, 5608.635438696471, 5614.0, 5631.0, 5649.442554565109, 5712.0, 5956.672383018932, 6018.969905150446, 6024.186734790791, 6185.0413185955795, 6189.473894718954, 6120.659592487412, 6128.044828652048, 6191.752329906798, 6243.125962365272, 6244.781432649881, 5690.5163061140265, 5698.886022279573, 5711.657300561979, 5727.541796656257, 5746.396628354005, 6141.938580614226, 6250.593711408744, 6287.779927702509, 6314.55798094129, 6322.557420980487, 5994.790182343597, 6097.390260973956, 6110.877782444326, 6126.724297406628, 6143.266246520345, 5177.922331165036, 6223.533235007392, 6225.719842607094, 6229.688515574203, 6238.313785483593, 5915.674637894957, 6128.17269668598, 6159.137720719103, 6169.753209871555, 6171.228596425447, 6083.11794789753, 6115.666295175977, 6129.540284478593, 6147.44672979434, 6152.3540028297275, 4892.461829198785, 4926.187179115477, 4928.01439712059, 4952.182889823139, 4962.280469331927, 4411.250087485135, 4412.955870441318, 4419.491758447755, 4441.800118994653, 4469.173202957463, 5537.833864984057, 5608.747606357723, 5677.432256774289, 5752.0, 5757.251557297608, 6137.254412462224, 6173.0, 6179.474744646755, 6187.0, 6262.624242545462, 5782.132680098019, 5785.739641716133, 5794.159846822187, 5797.159411885253, 5819.272590926134, 5236.528712415901, 5394.083005889008, 5395.73021348931, 5403.621746477787, 5406.08096623575, 6202.224721909762, 6256.5307601930945, 6258.092576576462, 6279.0, 6302.779402720883, 6233.594816336924, 6241.469475094486, 6247.5939063960695, 6255.96872015637, 6256.562040657111, 6194.566380353332, 6233.532485063729, 6247.125402443752, 6254.21822272216, 6286.0, 5950.702464876826, 6014.939850601438, 6072.240969878765, 6124.418180273045, 6153.600015998945, 6031.064603643036, 6137.46891820379, 6222.408871157233, 6225.408586184305, 6226.657533835682, 6031.668258245839, 6041.78853740117, 6046.771267369806, 6052.303985041776, 6077.179580756541, 5821.738021788945, 5877.853053673693, 5918.917978862516, 6040.7583696651645, 6057.273127224761, 5768.567357448292, 5815.912761308499, 5818.679972601548, 5838.2702162229725, 5838.299404071538, 5826.242588463472, 5944.078667806324, 5959.314678811992, 5968.8806223876145, 5985.341612422543, 5133.3326667534475, 5776.046952252831, 5796.652200867961, 5832.737526811219, 5835.124731290341], "value": 5642.137987030162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.3800526297393, 477.92831075338694, 479.9680686523973, 486.99678661956375, 537.981170659027, 39325.4101188482, 41273.79363103184, 41476.963075923115, 41515.584844151585, 42842.57157428425, 42133.36799948003, 42797.78601106998, 42914.0, 42919.92700182497, 42964.92587685309, 8843.557822108869, 9094.86357704635, 9315.161635452825, 9620.326577139604, 9634.084761947639, 11128.721781955435, 12364.752704945895, 12372.81440778389, 12461.81307280387, 12471.563495277667, 12256.754864902696, 12281.938590307036, 12291.0, 12304.753904921896, 12313.13808033438, 8859.246964008067, 9076.8638470423, 9077.45535267886, 12249.326287054208, 12271.018318534496, 8860.291176705849, 8908.866367004435, 11905.821412678817, 12220.2667839929, 12260.264384136892, 41098.794506027574, 41267.380989285186, 41278.761637150936, 41321.0, 41339.37990930139, 3240.9189770255934, 3242.238074052596, 3255.1048461672995, 3320.7509436792097, 3604.2611264690677, 31292.843535782402, 31385.84307078473, 31435.371292574022, 31819.727210911766, 32355.0, 3137.0, 3155.7475401967786, 3237.4172648923186, 3268.7221586165206, 3373.291608762163, 495332.5233373846, 496352.0, 496475.0, 497530.5123474396, 501862.0, 49451.752741236065, 58822.70588647029, 59091.0, 59224.703876480344, 59233.40766592279, 61894.38105618976, 61948.0, 62087.37912620906, 62107.6894615524, 62130.0680489793, 15690.215489225722, 16240.67518649644, 16249.59376015609, 16261.105639189722, 16261.723358729787, 11391.373474458984, 11734.471948762326, 11735.882641173479, 12543.498260069677, 12760.80858787119, 13345.466181352636, 13715.862841371458, 16401.671966560603, 16461.83538164603, 16573.41993030262, 5235.450277720861, 5560.443955604407, 6207.689615519206, 6483.837904052436, 6504.707288172043, 869.891263592051, 949.9572519236651, 1028.5114570579065, 1046.5133712823574, 1141.2068612314529, 29862.701372986427, 29946.55080173799, 30512.237194070327, 30657.0, 30686.38627227443, 1043.3896170740202, 1058.7237439505125, 1089.8583184186004, 1135.7728454309172, 1161.2509931094396, 15025.924870375578, 15471.0, 15500.30248638814, 15619.687606247811, 15928.256859637975, 165985.0, 184538.0, 185418.0, 185521.0, 185703.0, 57358.7132064337, 57779.0, 57786.42213577894, 57828.71085644629, 57896.0, 1223.8714934931882, 1260.5840072776145, 1353.4992052940474, 1359.4290398033047, 1379.4482207117233, 28764.993225237016, 29461.705382945896, 30251.848740757036, 31983.520247195844, 32193.0, 50595.98808023892, 51429.971400572525, 51514.22728658998, 51654.48345516497, 51664.74167629138, 565267.0, 612718.0, 613533.0, 613689.0, 838112.809435949, 42169.0, 42497.78751106225, 42781.0, 42849.14301714011, 43132.27470901129, 46703.06593868036, 47455.762721186176, 47759.5224047769, 48098.0, 48111.75944120257, 37944.62055379521, 38748.6125138745, 38868.416973744854, 39205.0, 40526.0, 38787.0, 39012.414813778385, 39586.0, 40110.0, 40535.594644053184, 38450.42324365081, 38918.0, 39207.0, 39358.81923542298, 39365.2126957465, 31998.0, 34472.82763586166, 35106.473402899486, 35393.646063540065, 35517.467237991965, 25816.741832582185, 25820.967161313336, 25856.35359115962, 26419.339516512242, 26466.603000955387], "value": 19802.19859594525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2679400053887, 360.22012343276805, 369.53254133521085, 370.30937301931897, 398.6113539299184, 15336.923315383421, 15538.456154034611, 16323.591910202236, 21285.255016074403, 21392.0, 19408.0, 20141.39575812728, 20287.289944851935, 20668.793312066875, 20875.895620521875, 4892.119418504668, 5660.5471562275025, 5938.64368137911, 5955.225820643311, 5962.0, 5786.363500014995, 5862.618929769573, 5924.88150236995, 5934.525237980973, 5944.732487038072, 5568.777248910059, 5821.27234095738, 5866.090755932837, 5872.0898260769645, 5873.412658734135, 5769.480746732793, 5792.333881603628, 5810.360860305363, 5818.679972601505, 5827.796027139051, 5005.824796132154, 5084.161113416282, 5087.313212716273, 5276.604254680914, 5342.0651386007685, 12799.424025918763, 13063.281519516422, 13076.0, 14583.343749531297, 24572.508549829083, 2174.945626359338, 2178.6405243134864, 2321.454458202321, 2637.5779875220064, 2953.3797902440515, 9982.650607228785, 10231.232657550714, 10689.465526723632, 12604.432800523906, 15616.297266622916, 1702.0808763267944, 1773.5300145461463, 1780.5014595913165, 1795.7485951966735, 1839.5585059585737, 113821.43089284583, 119438.0, 121907.0, 123983.0, 125565.99999999908, 11168.720781980353, 11195.832062519068, 11206.327620342805, 11408.315501069963, 11612.361320127475, 14393.136411815325, 14410.927945360207, 14421.134731916118, 14444.638884027983, 14447.255021545872, 8121.984751906011, 8404.907362042895, 8417.831643367099, 8515.914840851636, 8555.443896146731, 6778.559718501336, 8344.958275208706, 8375.8324833503, 8378.53918034514, 8546.444481108832, 4322.200392927256, 4434.711743736647, 4458.0, 4532.9320060199125, 4571.771411429481, 1553.4252326639216, 1700.5153531243536, 1809.4933418642802, 1879.6710575649206, 1886.5566591850904, 755.5882044285877, 851.8594431918788, 881.2333270054964, 982.8918818929913, 1038.231708535678, 20386.28647997313, 21119.049642766113, 21156.47108822292, 21262.0, 21273.78726212718, 898.1063841477771, 921.8432866412726, 932.9673461428818, 935.9859602105973, 969.1955676788249, 9484.525773711286, 10229.897701022894, 11197.49611267495, 11220.831687524693, 11222.55109795599, 501992.0, 508397.0, 508968.0, 590529.0, 603961.0, 84499.15500844913, 92873.53563232141, 116978.41510792561, 127357.0, 138846.0, 975.5024937282037, 1005.7887843552936, 1011.7672935224939, 1016.1464369929295, 1028.7736697926593, 18682.813171868107, 18857.90571047136, 18860.436171329766, 19030.09699030086, 19275.903620481808, 38111.856644300715, 46902.82742931454, 48727.756361217966, 50509.49490505048, 52799.73600131975, 75772.0, 77203.61398192972, 77253.0, 78418.6079069601, 78641.0, 6978.337057979484, 7349.154847192642, 7353.0, 7526.5860377679755, 7675.53946763196, 11028.062614677843, 11125.443727813416, 12878.484860605846, 13402.26287554194, 13646.385912634154, 9846.950765246414, 10945.069669078217, 11231.719207019563, 11315.886841131483, 11611.709707257049, 9637.662681806103, 10146.695599131743, 10254.846177307198, 11442.599509017144, 11500.769984600429, 11226.270292430965, 11278.041366483612, 11803.645890623295, 11819.231749936227, 11972.940135299268, 7342.192358840416, 7440.255974402517, 7502.219224684609, 7553.32020118192, 7717.652705628372, 9754.561044752862, 11046.558137674403, 11542.538298467967, 11670.649880503599, 11717.0], "value": 9230.87434087943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.4916403004995, 1537.4849425442474, 1743.3462451580658, 1858.8327050565447, 1921.7213504041922, 8384.413091083627, 8893.866592001108, 8915.0, 8923.330750193729, 8933.910660893389, 7859.68561257549, 8085.636146373413, 8400.0, 8643.178898004695, 8692.56537173142, 2336.521013192296, 2371.2530552875874, 2372.406898275431, 2380.33350661815, 2383.2731017039796, 2060.2377120465376, 2241.60771864923, 2293.7018187635585, 2311.05246848792, 2331.7901388875, 2314.2363020203293, 2324.5699545584034, 2343.6367363058753, 2349.0, 2354.1878052072007, 2109.799569040939, 2233.7207849018873, 2342.6837376954063, 2347.788699017097, 2349.729781075164, 2308.134449581407, 2313.9537209255723, 2318.5362927414417, 2321.883905804703, 2326.720793504773, 8728.51993140377, 8735.43219690718, 8853.867191992125, 8873.06832782555, 8891.02198758136, 5129.846104616868, 5310.654807437504, 5322.0, 5322.387925388591, 5322.787088516493, 3460.0, 3538.168530395355, 3686.4838922550866, 3694.261147770457, 3694.501242332278, 1043.2227990147312, 1075.4568942683973, 1080.7946490166844, 1088.2491081154058, 1088.7985722641338, 11500.022498087672, 11581.305121692725, 11605.187636865507, 11614.36121013344, 11634.941825290904, 4507.143642707875, 4566.543345665407, 4772.069446457907, 4774.021325628238, 4786.353842231324, 9860.359076659994, 9881.80236395268, 9887.802243955224, 9890.20878329732, 9895.554700038516, 5331.86670333237, 5333.040052790535, 5336.519713225828, 5340.786368545215, 5344.839654810362, 4345.217860785089, 4432.889177770581, 4510.391097201901, 4808.158572249842, 4872.9512704873205, 3808.7524310919703, 3829.9617003830163, 3930.194310166409, 3942.448057271984, 3979.542352629485, 1254.8243245945575, 1265.936703164838, 1270.6950331920273, 1271.726578785547, 1274.4010315151863, 808.5270116981632, 820.9055958564722, 836.3482122807528, 890.2210565754964, 946.5976959792199, 5433.646812957145, 5828.88342233153, 5946.643201407929, 5947.583669143119, 6062.787802426894, 774.0788461730635, 911.4485736129546, 924.4915296586851, 935.7052528453515, 960.4333443268505, 3814.809259537012, 3824.330742120118, 3851.730378873452, 3917.177392747555, 3969.702272329587, 10289.125424339016, 10742.301750386356, 10757.9462102689, 10765.892341076646, 10769.407682577534, 10237.385756854612, 10569.0, 10581.206409519316, 10708.1433485321, 10709.196810239264, 8023.6790528378215, 8261.215184557457, 8264.545450000187, 8267.131951145044, 8273.048599411144, 5620.915686264628, 5800.854978625568, 5824.184614154106, 5999.3100793407575, 6172.598781079216, 9380.249580033444, 10050.0, 10062.195024397884, 10093.343932644355, 10099.89900101019, 10297.43364114951, 10315.226358023028, 10315.432651204255, 10317.432541210306, 10324.587016519255, 6930.7227710891, 7132.679029443584, 7158.964205178941, 7453.962730186315, 7597.050368703912, 7985.560794156145, 8144.511329320259, 8242.423030387816, 8420.705275315335, 8466.915330846612, 7421.666025028779, 7544.132424771222, 7557.244275572399, 7602.315791578784, 7684.231576842271, 7276.96361518189, 7329.083864516935, 7359.411247100326, 7435.330820226206, 7539.019927409621, 6815.079964204769, 6960.37356637905, 6969.512134150561, 6971.65141742921, 6974.546654467444, 4869.293952376956, 4890.24201248807, 4981.750912454435, 5028.094942910165, 5068.721220332917, 4867.14824905633, 4968.22992436173, 5050.696958182521, 5121.795128194831, 5176.275321455075], "value": 4566.3528925662995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.22208979339433, 296.9792114551981, 304.4854196408069, 307.4511996086985, 312.9812211267323, 4561.133384656913, 5162.225666150075, 5273.446288139748, 5279.260903473517, 5292.894142117165, 4475.440569928759, 4687.015726697389, 4890.6087512998965, 5032.144535428976, 5087.956968821392, 3768.0, 4025.939610905839, 4027.5972402759635, 4045.656119229862, 4065.0, 3655.1775850433705, 3798.65812076913, 4015.277250094982, 4020.7989600520004, 4043.2317859606583, 3767.698584113264, 3980.7412518186225, 4007.138465229975, 4011.3782363733667, 4045.5347635022054, 3705.8882335299436, 3964.7026473014353, 4002.7598344099447, 4026.295398305285, 4026.7382620129597, 2889.8844046238123, 3472.791632502058, 3484.8083355415442, 3487.720982321408, 3502.4045912194733, 4617.561331673485, 4845.757712114416, 4885.022995400898, 4982.128127577659, 5000.074926197693, 4327.956720432787, 4462.062966776981, 4496.190685676577, 4506.5493450655, 4511.6165125964335, 4843.975780121077, 4892.3884514435695, 5472.753726082336, 5499.945000550023, 5512.558995280408, 3767.095896984704, 3781.0, 3785.7696248719276, 3787.9053023674355, 3793.3740932746064, 5378.650387724785, 5624.6625202487985, 5656.519195868315, 5697.03150464422, 5704.657720536781, 3789.488419063446, 3798.848046078126, 3801.41503777434, 3801.6198380161763, 3815.8855234343014, 3498.755087143926, 3677.3013127505687, 3854.4603755474254, 3858.9807050964564, 3879.689624830063, 4224.809883555247, 4487.259602165672, 4505.211587972091, 4508.594226519629, 4589.5181005994555, 4541.046380260116, 4781.42622885256, 5444.346678398539, 5457.617966742369, 5518.771518661548, 1229.416027386984, 1283.7355205122947, 1307.7907534794388, 1328.6182370334743, 1343.3350491506767, 871.3769654696922, 921.824853277875, 935.2284365398573, 943.3679434778714, 948.5778828421425, 5166.819161329336, 5182.0, 5203.453637368098, 5214.357477394685, 5229.294045303911, 5327.307450031549, 5517.15866328841, 5563.554915606742, 5602.635828671205, 5654.031633392502, 4317.568243175658, 5132.769025393866, 5179.870503237374, 5207.739613019409, 5216.843494695166, 2580.87095645217, 2646.8676566171616, 2834.2286071205494, 2906.0648744767514, 2912.577676236976, 5464.836054918359, 5641.576881733887, 5645.520130788929, 5685.715714214272, 5896.7641294348605, 5821.592488525763, 5851.385604511465, 5854.648721076749, 5864.149698293723, 5868.970655146697, 4950.752462376939, 5301.9057472299, 5832.591718579659, 5898.380670029671, 5899.882002360015, 4980.8754781130765, 5378.757955891916, 5458.399576046552, 5597.412271711411, 5674.573832410726, 5610.0, 5610.689693081102, 5621.381648018633, 5624.987502249634, 5625.128105143709, 4987.576056035277, 5126.641440018324, 5573.913086948006, 5890.408220383018, 5935.910961335497, 3701.5002974598083, 3760.172761992357, 3790.521696538297, 3831.2912111258393, 3837.642063194705, 4980.726060066586, 5714.9142762857755, 5723.742431590672, 5728.971355143198, 5744.5011420032415, 5006.3992320921725, 5234.18870075139, 5332.78668853257, 5498.560115190855, 5575.944240557543, 4431.82272709088, 4572.588467037983, 5451.48210919962, 5462.289902312674, 5464.617476776588, 4424.296645791329, 5151.768170432414, 5301.946980530145, 5351.892962140658, 5367.570594352521, 5325.068113080118, 5514.393416724238, 5515.731381782132, 5518.172274158908, 5567.498925096761, 4846.369971903771, 4953.900921981468, 5412.377576578744, 5437.755301011543, 5501.917471237854], "value": 4028.674135508198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.2328726756932, 700.7442283566496, 758.5562445969111, 787.8694074003809, 813.1055838577565, 15774.605634859134, 16171.43399981001, 16176.35294588215, 16194.271257793398, 16202.594935126614, 14517.20155391453, 14622.341994610242, 14631.561053168421, 14647.853521464782, 14649.560513184622, 676.2662511175381, 699.4369532526314, 699.8040548646387, 827.7392621324263, 845.5687599324327, 850.1838235294125, 859.5358506406533, 860.5697151424288, 864.2178828160494, 864.5288317866746, 907.7912080221553, 909.172652885874, 909.6770646420524, 909.7543663210929, 910.0944560162615, 717.7990162754437, 901.9188273055456, 902.4540153207281, 903.7921278106006, 903.8824952756165, 544.8528897197774, 653.2944420026407, 667.9398854103106, 674.652553934724, 676.1142902797351, 15359.4624188154, 15371.0, 15392.615184620363, 15405.0, 15429.614259643486, 13799.2410417427, 14093.78859317111, 14284.214368209745, 14355.71288574233, 14502.274886255645, 12886.935565322114, 12973.091883568153, 13058.542950996669, 13228.206307621573, 13327.933360333232, 4289.120730250323, 4289.656827453796, 4388.100439409914, 4470.977645111786, 4479.059397526524, 121061.0, 123522.38238808748, 124825.0, 125034.0, 125910.0, 10200.846987295197, 11075.280106793032, 11204.047655949336, 11221.326720396803, 11538.76922461546, 20431.79568204329, 20973.26593569248, 21178.258760943292, 21208.0, 21221.575568488544, 7603.5553244883295, 7725.150233474314, 7839.451238413257, 7874.291313781787, 7918.2477664621765, 4162.708610397243, 4198.0, 4279.764612946318, 4366.323219900921, 4397.516273209944, 3424.4692072728767, 3503.4219353806848, 3547.255076433926, 3548.929021419557, 3555.1645363339594, 642.1491523731075, 642.6529673976, 644.2880616918238, 659.5020759326677, 659.8548319369731, 555.910415585449, 600.6006006006033, 607.9483243924316, 609.7256234694405, 623.1338439569003, 8509.914900850912, 9067.68263110788, 9078.0, 9260.7684807879, 9495.952520237355, 513.9871503212449, 549.884524249912, 573.7274794472676, 620.3424370167637, 623.4046485606235, 4279.486461624625, 4288.333474878934, 4480.171168333869, 4516.345129956204, 4673.556012178838, 153349.26555141032, 155499.0, 157186.6422003671, 158195.0, 160158.0, 91103.61204849393, 133884.0, 136476.65751863396, 137982.0, 142831.0, 118671.40664296623, 120274.19588706351, 121295.18057228973, 121340.0, 124210.0, 8481.491110533389, 8608.741737747878, 8644.524551149747, 8722.694705685271, 8873.157050080232, 65306.67346663236, 65438.820147481565, 66102.67794644176, 67526.0, 69482.65258673675, 56884.715576421855, 62579.0, 74186.51626967538, 80188.0, 97155.0, 13307.800382994457, 13360.064795464614, 13426.664333391744, 13593.932030339785, 13842.169469831842, 12929.418176181905, 14123.858761412665, 14628.853711462749, 15077.321520531383, 15238.619034524225, 13633.590992270249, 13776.311184440938, 13923.955703322088, 14111.506097286545, 14142.292885355486, 11295.3787541686, 13370.0, 13499.46002159942, 13626.659333516742, 13629.795553066513, 12818.487260509475, 13347.599572012856, 13374.0, 13414.262215577846, 13551.932240338736, 11180.385078820744, 11244.55021799119, 11453.541858325574, 11884.643460696192, 11943.462544185359, 8175.0, 9880.505974701093, 10230.744231394274, 11525.538978440769, 11587.478563464496], "value": 7801.365700483565, "unit": "score"}], "name": "dromaeo_css", "value": 7273.186795540024, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0, 114.0, 112.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 130.0, 179.0, 141.0, 113.0, 111.0, 115.0, 144.0, 112.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 185.0, 182.0, 186.0, 181.0, 183.0, 182.0, 177.0, 179.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 86.0, 71.0, 77.0, 67.0, 58.0, 84.0, 59.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 95.0, 72.0, 67.0, 68.0, 92.0, 67.0, 68.0, 90.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 87.0, 88.0, 89.0, 87.0, 90.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 110.0, 128.0, 94.0, 93.0, 94.0, 95.0, 93.0, 95.0], "value": 98.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 83.0, 83.0, 84.0, 83.0, 83.0, 84.0, 83.0, 81.0], "value": 83.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 53.0, 55.0, 56.0, 56.0, 56.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 72.0, 72.0, 72.0, 69.0, 69.0, 71.0, 69.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 86.0, 77.0, 95.0, 102.0, 96.0, 86.0, 86.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 162.0, 159.0, 153.0, 139.0, 168.0, 148.0, 209.0, 179.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 170.0, 174.0, 173.0, 173.0, 173.0, 172.0, 173.0, 172.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 81.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1459.6999999999998, "alertThreshold": 2.0}]} 19:39:25 INFO - Return code: 0 19:39:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:39:25 INFO - # TBPL SUCCESS # 19:39:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:39:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:39:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:39:26 INFO - 2016-10-08 19:39:26.009 screenresolution[1672:11508] starting screenresolution argv=screenresolution get 19:39:26 INFO - 2016-10-08 19:39:26.012 screenresolution[1672:11508] Display 0: 1600x1200x32@60 19:39:26 INFO - 2016-10-08 19:39:26.018 screenresolution[1673:11511] starting screenresolution argv=screenresolution list 19:39:26 INFO - Available Modes on Display 0 19:39:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:39:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:39:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:39:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:39:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:39:26 INFO - Intel Iris: 19:39:26 INFO - Chipset Model: Intel Iris 19:39:26 INFO - Type: GPU 19:39:26 INFO - Bus: Built-In 19:39:26 INFO - VRAM (Dynamic, Max): 1536 MB 19:39:26 INFO - Vendor: Intel (0x8086) 19:39:26 INFO - Device ID: 0x0a2e 19:39:26 INFO - Revision ID: 0x0009 19:39:26 INFO - Displays: 19:39:26 INFO - Display: 19:39:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:39:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:39:26 INFO - Display Serial Number: 1600x1200 60 19:39:26 INFO - Main Display: Yes 19:39:26 INFO - Mirror: Off 19:39:26 INFO - Online: Yes 19:39:26 INFO - Rotation: Supported 19:39:26 INFO - Return code: 0 19:39:26 INFO - Running post-action listener: _resource_record_post_action 19:39:26 INFO - [mozharness: 2016-10-09 02:39:26.256808Z] Finished run-tests step (success) 19:39:26 INFO - Running post-run listener: _resource_record_post_run 19:39:26 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31833088; Write bytes: 704126464; Read time: 194; Write time: 1931 19:39:26 INFO - TinderboxPrint: CPU usage
53.3% 19:39:26 INFO - TinderboxPrint: I/O read bytes / time
31,833,088 / 194 19:39:26 INFO - TinderboxPrint: I/O write bytes / time
704,126,464 / 1,931 19:39:26 INFO - TinderboxPrint: CPU idle
2,267.9 (46.7%) 19:39:26 INFO - TinderboxPrint: CPU system
963.5 (19.9%) 19:39:26 INFO - TinderboxPrint: CPU user
1,622.3 (33.4%) 19:39:26 INFO - TinderboxPrint: Swap in / out
435,888,128 / 0 19:39:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387566080; Write bytes: 381588992; Read time: 29012; Write time: 782 19:39:26 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 30891008; Write bytes: 293959680; Read time: 182; Write time: 1082 19:39:26 INFO - Running post-run listener: _upload_blobber_files 19:39:26 INFO - Blob upload gear active. 19:39:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:39:26 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 19:39:26 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'] 19:39:26 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 19:39:27 INFO - (blobuploader) - INFO - Open directory for files ... 19:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:28 INFO - (blobuploader) - INFO - Done attempting. 19:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:29 INFO - (blobuploader) - INFO - Done attempting. 19:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:32 INFO - (blobuploader) - INFO - Done attempting. 19:39:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:39:32 INFO - Return code: 0 19:39:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:39:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:39:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172d78ceec4c62274f80452ee1bc375d03785df86cfa3df679ddab33c3817f6dfd2296b22f881a076e907d3203b50d6009101b28489bb2ba51f4701b5981006a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/040564261e7c98b4b9d0a982055cc728499c47a9e357c3d1c66376c13525348a5230b1bd0ab9666e5ff2f68fa533d47669d9d2a5319c65e1b3107871ee418100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/395ad9dd522558d5d47562f0b897bfbc5b7eeb75f989e034a5e1a741ba7d1fb711be5ba42108bcb6ca93c14fb47a7e4ab884f7b7b5dbebb3f7590b5260ddc560"} 19:39:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:39:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:39:32 INFO - Contents: 19:39:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172d78ceec4c62274f80452ee1bc375d03785df86cfa3df679ddab33c3817f6dfd2296b22f881a076e907d3203b50d6009101b28489bb2ba51f4701b5981006a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/040564261e7c98b4b9d0a982055cc728499c47a9e357c3d1c66376c13525348a5230b1bd0ab9666e5ff2f68fa533d47669d9d2a5319c65e1b3107871ee418100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/395ad9dd522558d5d47562f0b897bfbc5b7eeb75f989e034a5e1a741ba7d1fb711be5ba42108bcb6ca93c14fb47a7e4ab884f7b7b5dbebb3f7590b5260ddc560"} 19:39:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:39:32 INFO - Copying logs to upload dir... 19:39:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:39:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.375389 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-08 19:39:32.384718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 19:39:32.389141) ========= 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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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/172d78ceec4c62274f80452ee1bc375d03785df86cfa3df679ddab33c3817f6dfd2296b22f881a076e907d3203b50d6009101b28489bb2ba51f4701b5981006a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/040564261e7c98b4b9d0a982055cc728499c47a9e357c3d1c66376c13525348a5230b1bd0ab9666e5ff2f68fa533d47669d9d2a5319c65e1b3107871ee418100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/395ad9dd522558d5d47562f0b897bfbc5b7eeb75f989e034a5e1a741ba7d1fb711be5ba42108bcb6ca93c14fb47a7e4ab884f7b7b5dbebb3f7590b5260ddc560"} build_url:https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010348 build_url: 'https://queue.taskcluster.net/v1/task/RHmpkIrwSTiY4NLwYq3xoA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172d78ceec4c62274f80452ee1bc375d03785df86cfa3df679ddab33c3817f6dfd2296b22f881a076e907d3203b50d6009101b28489bb2ba51f4701b5981006a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/040564261e7c98b4b9d0a982055cc728499c47a9e357c3d1c66376c13525348a5230b1bd0ab9666e5ff2f68fa533d47669d9d2a5319c65e1b3107871ee418100", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/395ad9dd522558d5d47562f0b897bfbc5b7eeb75f989e034a5e1a741ba7d1fb711be5ba42108bcb6ca93c14fb47a7e4ab884f7b7b5dbebb3f7590b5260ddc560"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 19:39:32.423385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:39:32.423722) ========= 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.FTmKMkaJ5f/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.pIWvQxQRZe/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.005034 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 19:39:32.473967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 19:39:32.474517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 19:39:32.477975) ========= ========= Total master_lag: 0.29 =========