builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1476163608.87 results: success (0) buildid: 20161010201346 builduid: c7939a680f7c47f09fb04416d5b3de6d revision: 2976adccde9b75596c469428228177ed3d0f0b61 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:48.870506) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:48.870989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:48.871273) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:48.899714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:48.900041) ========= 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.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-10 22:26:48-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.7M=0s 2016-10-10 22:26:49 (58.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135059 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:49.056994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:49.057448) ========= 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.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.021407 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:49.109904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 22:26:49.110534) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2976adccde9b75596c469428228177ed3d0f0b61 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2976adccde9b75596c469428228177ed3d0f0b61 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-10 22:26:49,187 truncating revision to first 12 chars 2016-10-10 22:26:49,188 Setting DEBUG logging. 2016-10-10 22:26:49,188 attempt 1/10 2016-10-10 22:26:49,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2976adccde9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 22:26:50,402 unpacking tar archive at: fx-team-2976adccde9b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.474483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 22:26:50.608015) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:50.608331) ========= script_repo_revision: 2976adccde9b75596c469428228177ed3d0f0b61 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:50.608727) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:50.609018) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 22:26:50.627491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-10 22:26:50.627925) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:26:50 INFO - MultiFileLogger online at 20161010 22:26:50 in /builds/slave/test 22:26:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:26:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:26:50 INFO - {'append_to_log': False, 22:26:50 INFO - 'base_work_dir': '/builds/slave/test', 22:26:50 INFO - 'blob_upload_branch': 'Fx-Team', 22:26:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:26:50 INFO - 'branch': 'Fx-Team', 22:26:50 INFO - 'buildbot_json_path': 'buildprops.json', 22:26:50 INFO - 'config_files': ('talos/mac_config.py',), 22:26:50 INFO - 'default_actions': ('clobber', 22:26:50 INFO - 'read-buildbot-config', 22:26:50 INFO - 'download-and-extract', 22:26:50 INFO - 'populate-webroot', 22:26:50 INFO - 'create-virtualenv', 22:26:50 INFO - 'install', 22:26:50 INFO - 'run-tests'), 22:26:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:26:50 INFO - 'download_minidump_stackwalk': True, 22:26:50 INFO - 'download_symbols': 'ondemand', 22:26:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:26:50 INFO - 'tooltool.py': '/tools/tooltool.py', 22:26:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:26:50 INFO - '/tools/misc-python/virtualenv.py')}, 22:26:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:26:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:26:50 INFO - 'installer_path': 'installer.exe', 22:26:50 INFO - 'log_level': 'info', 22:26:50 INFO - 'log_name': 'talos', 22:26:50 INFO - 'log_to_console': True, 22:26:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:26:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:26:50 INFO - 'opt_config_files': (), 22:26:50 INFO - 'pip_index': False, 22:26:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:26:50 INFO - 'cmd': ('bash', 22:26:50 INFO - '-c', 22:26:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:26:50 INFO - 'enabled': True, 22:26:50 INFO - 'halt_on_failure': False, 22:26:50 INFO - 'name': 'check_screen_resolution'},), 22:26:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:26:50 INFO - 'cmd': ('bash', 22:26:50 INFO - '-c', 22:26:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:26:50 INFO - 'enabled': True, 22:26:50 INFO - 'halt_on_failure': False, 22:26:50 INFO - 'name': 'check_screen_resolution'},), 22:26:50 INFO - 'run_cmd_checks_enabled': True, 22:26:50 INFO - 'sps_profile': False, 22:26:50 INFO - 'sps_profile_interval': 0, 22:26:50 INFO - 'suite': 'dromaeojs', 22:26:50 INFO - 'system_bits': '32', 22:26:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:26:50 INFO - 'title': 't-yosemite-r7-0029', 22:26:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:26:50 INFO - 'use_talos_json': True, 22:26:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:26:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:26:50 INFO - 'work_dir': 'build'} 22:26:50 INFO - [mozharness: 2016-10-11 05:26:50.843780Z] Running clobber step. 22:26:50 INFO - Running pre-action listener: _resource_record_pre_action 22:26:50 INFO - Running main action method: clobber 22:26:50 INFO - rmtree: /builds/slave/test/build 22:26:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:26:52 INFO - Running post-action listener: _resource_record_post_action 22:26:52 INFO - [mozharness: 2016-10-11 05:26:52.399706Z] Finished clobber step (success) 22:26:52 INFO - [mozharness: 2016-10-11 05:26:52.399910Z] Running read-buildbot-config step. 22:26:52 INFO - Running pre-action listener: _resource_record_pre_action 22:26:52 INFO - Running main action method: read_buildbot_config 22:26:52 INFO - Using buildbot properties: 22:26:52 INFO - { 22:26:52 INFO - "project": "", 22:26:52 INFO - "product": "firefox", 22:26:52 INFO - "script_repo_revision": "production", 22:26:52 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:26:52 INFO - "repository": "", 22:26:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:26:52 INFO - "buildid": "20161010201346", 22:26:52 INFO - "pgo_build": "False", 22:26:52 INFO - "basedir": "/builds/slave/test", 22:26:52 INFO - "buildnumber": 170, 22:26:52 INFO - "slavename": "t-yosemite-r7-0029", 22:26:52 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 22:26:52 INFO - "platform": "yosemite_r7", 22:26:52 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:26:52 INFO - "branch": "fx-team", 22:26:52 INFO - "repo_path": "integration/fx-team", 22:26:52 INFO - "revision": "2976adccde9b75596c469428228177ed3d0f0b61", 22:26:52 INFO - "stage_platform": "macosx64", 22:26:52 INFO - "builduid": "c7939a680f7c47f09fb04416d5b3de6d", 22:26:52 INFO - "slavebuilddir": "test" 22:26:52 INFO - } 22:26:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:26:52 INFO - Running post-action listener: _resource_record_post_action 22:26:52 INFO - [mozharness: 2016-10-11 05:26:52.405915Z] Finished read-buildbot-config step (success) 22:26:52 INFO - [mozharness: 2016-10-11 05:26:52.406096Z] Running download-and-extract step. 22:26:52 INFO - Running pre-action listener: _resource_record_pre_action 22:26:52 INFO - Running main action method: download_and_extract 22:26:52 INFO - mkdir: /builds/slave/test/build/tests 22:26: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')]} 22:26:52 INFO - trying https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:26:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/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 22:26:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/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 22:26:53 INFO - Downloaded 1199 bytes. 22:26:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:26:53 INFO - Using the following test package requirements: 22:26:53 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:26:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:26:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:26:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'jsshell-mac.zip'], 22:26:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:26:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:26:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:26:53 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:26:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:26:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:26:53 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:26:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:26:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:26:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:26:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:26:53 INFO - Http code: 200 22:26:53 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:26:53 INFO - x-amz-cf-id: xTC1hv8YLP5Bg5maRfFaV_bg85YjiN5Hzfm82ox0od6mXE1y3Rxolg== 22:26:53 INFO - x-amz-version-id: ohw61SWzo2eGcVvZMqpGuYXiElnk0a.. 22:26:54 INFO - Expected file size: 16508419 22:26:54 INFO - Obtained file size: 16508419 22:27:00 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 22:27:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:27:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:27:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:27:01 INFO - Http code: 200 22:27:01 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 22:27:01 INFO - x-amz-cf-id: VyelK7Yq4CSgmNI2odV_bUVEfPtvX-r6hWertYZA6mOyXHQSr7qSIQ== 22:27:01 INFO - x-amz-version-id: ohw61SWzo2eGcVvZMqpGuYXiElnk0a.. 22:27:01 INFO - Expected file size: 16508419 22:27:01 INFO - Obtained file size: 16508419 22:27:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:27:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:27:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:27:09 INFO - Http code: 200 22:27:09 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 22:27:09 INFO - x-amz-cf-id: TlJIliYRleFoYNJNKaOK-dAt_a86lroeJLv5LJgkUYhxFBD-OKVIBw== 22:27:09 INFO - x-amz-version-id: Li3EtyoW1kqx5PonBrZpK8mMzH6jKZzT 22:27:09 INFO - Expected file size: 14409006 22:27:09 INFO - Obtained file size: 14409006 22:27:09 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')]} 22:27:09 INFO - trying https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:27:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:27:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/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 22:27:12 INFO - Downloaded 111686350 bytes. 22:27:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:27:12 INFO - mkdir: /builds/slave/test/properties 22:27:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:27:12 INFO - Writing to file /builds/slave/test/properties/build_url 22:27:12 INFO - Contents: 22:27:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:27:13 INFO - Running post-action listener: _resource_record_post_action 22:27:13 INFO - Running post-action listener: set_extra_try_arguments 22:27:13 INFO - [mozharness: 2016-10-11 05:27:13.509079Z] Finished download-and-extract step (success) 22:27:13 INFO - [mozharness: 2016-10-11 05:27:13.509200Z] Running populate-webroot step. 22:27:13 INFO - Running pre-action listener: _resource_record_pre_action 22:27:13 INFO - Running main action method: populate_webroot 22:27:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:27:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:27:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:27:13 INFO - '--tppagecycles', 22:27:13 INFO - '7'], 22:27:13 INFO - 'tests': ['tsvgm']}}, 22:27:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:27:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:27:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:27:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:27:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:27:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:27:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['damp', 'tps']}, 22:27:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'tests': ['damp', 'tps']}, 22:27:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['dromaeo_dom']}, 22:27:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:27:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['basic_compositor_video']}, 22:27:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:27:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'other-e10s': {'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['a11yr', 22:27:13 INFO - 'ts_paint', 22:27:13 INFO - 'tpaint', 22:27:13 INFO - 'sessionrestore', 22:27:13 INFO - 'sessionrestore_no_auto_restore', 22:27:13 INFO - 'tabpaint']}, 22:27:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['tsvgx', 22:27:13 INFO - 'tsvgr_opacity', 22:27:13 INFO - 'tart', 22:27:13 INFO - 'tscrollx', 22:27:13 INFO - 'cart']}, 22:27:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:27:13 INFO - 'tsvgr_opacity', 22:27:13 INFO - 'tart', 22:27:13 INFO - 'tscrollx', 22:27:13 INFO - 'cart']}, 22:27:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'talos_options': ['--disable-e10s'], 22:27:13 INFO - 'tests': ['tp5o']}, 22:27:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'tests': ['tp5o']}, 22:27:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'talos_options': ['--disable-e10s', 22:27:13 INFO - '--xperf_path', 22:27:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:27:13 INFO - 'tests': ['tp5n']}, 22:27:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:13 INFO - 'talos_options': ['--xperf_path', 22:27:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:27:13 INFO - 'tests': ['tp5n']}}, 22:27:13 INFO - 'talos.zip': {'path': '', 22:27:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:27:13 INFO - Running post-action listener: _resource_record_post_action 22:27:13 INFO - [mozharness: 2016-10-11 05:27:13.528400Z] Finished populate-webroot step (success) 22:27:13 INFO - [mozharness: 2016-10-11 05:27:13.528513Z] Running create-virtualenv step. 22:27:13 INFO - Running pre-action listener: _resource_record_pre_action 22:27:13 INFO - Running main action method: create_virtualenv 22:27:13 INFO - Creating virtualenv /builds/slave/test/build/venv 22:27:13 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 22:27:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:27:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:27:13 INFO - Using real prefix '/tools/python27' 22:27:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:27:14 INFO - Installing distribute.............................................................................................................................................................................................done. 22:27:17 INFO - Installing pip.................done. 22:27:17 INFO - Return code: 0 22:27:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:27:17 INFO - Reading from file tmpfile_stdout 22:27:17 INFO - Output received: 22:27:17 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) 22:27:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:27:17 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')]} 22:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:17 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')]} 22:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:17 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:17 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 22:27:17 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 22:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:17 INFO - 'HOME': '/Users/cltbld', 22:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:17 INFO - 'LOGNAME': 'cltbld', 22:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:17 INFO - 'MOZ_NO_REMOTE': '1', 22:27:17 INFO - 'NO_EM_RESTART': '1', 22:27:17 INFO - 'PAGER': '/bin/cat', 22:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:17 INFO - 'PWD': '/builds/slave/test', 22:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:17 INFO - 'SHELL': '/bin/bash', 22:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:17 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:17 INFO - 'USER': 'cltbld', 22:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:17 INFO - 'XPC_FLAGS': '0x0', 22:27:17 INFO - 'XPC_SERVICE_NAME': '0', 22:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:17 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 22:27:17 INFO - Cleaning up... 22:27:17 INFO - Return code: 0 22:27:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:27:17 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')]} 22:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:17 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')]} 22:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:17 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:17 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 22:27:17 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 22:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:17 INFO - 'HOME': '/Users/cltbld', 22:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:17 INFO - 'LOGNAME': 'cltbld', 22:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:17 INFO - 'MOZ_NO_REMOTE': '1', 22:27:17 INFO - 'NO_EM_RESTART': '1', 22:27:17 INFO - 'PAGER': '/bin/cat', 22:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:17 INFO - 'PWD': '/builds/slave/test', 22:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:17 INFO - 'SHELL': '/bin/bash', 22:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:17 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:17 INFO - 'USER': 'cltbld', 22:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:17 INFO - 'XPC_FLAGS': '0x0', 22:27:17 INFO - 'XPC_SERVICE_NAME': '0', 22:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:18 INFO - Downloading/unpacking psutil>=3.1.1 22:27:18 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 22:27:18 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 22:27:18 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 22:27:18 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 22:27:18 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 22:27:18 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 22:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:27:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:22 INFO - Installing collected packages: psutil 22:27:22 INFO - Running setup.py install for psutil 22:27:22 INFO - building 'psutil._psutil_osx' extension 22:27:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:27:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:27:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:27:22 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 22:27:22 INFO - building 'psutil._psutil_posix' extension 22:27:22 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 22:27:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:27:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:27:22 INFO - ^ 22:27:22 INFO - 1 warning generated. 22:27:22 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 22:27:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:22 INFO - Successfully installed psutil 22:27:22 INFO - Cleaning up... 22:27:22 INFO - Return code: 0 22:27:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:22 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:22 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 22:27:22 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 22:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:22 INFO - 'HOME': '/Users/cltbld', 22:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:22 INFO - 'LOGNAME': 'cltbld', 22:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:22 INFO - 'MOZ_NO_REMOTE': '1', 22:27:22 INFO - 'NO_EM_RESTART': '1', 22:27:22 INFO - 'PAGER': '/bin/cat', 22:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:22 INFO - 'PWD': '/builds/slave/test', 22:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:22 INFO - 'SHELL': '/bin/bash', 22:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:22 INFO - 'USER': 'cltbld', 22:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:22 INFO - 'XPC_FLAGS': '0x0', 22:27:22 INFO - 'XPC_SERVICE_NAME': '0', 22:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:27:23 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 22:27:23 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 22:27:23 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 22:27:23 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 22:27:23 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 22:27:23 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 22:27:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:27:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:27:25 INFO - Installing collected packages: mozsystemmonitor 22:27:25 INFO - Running setup.py install for mozsystemmonitor 22:27:25 INFO - Successfully installed mozsystemmonitor 22:27:25 INFO - Cleaning up... 22:27:26 INFO - Return code: 0 22:27:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:27:26 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')]} 22:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:26 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')]} 22:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:26 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:26 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 22:27:26 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 22:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:26 INFO - 'HOME': '/Users/cltbld', 22:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:26 INFO - 'LOGNAME': 'cltbld', 22:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:26 INFO - 'MOZ_NO_REMOTE': '1', 22:27:26 INFO - 'NO_EM_RESTART': '1', 22:27:26 INFO - 'PAGER': '/bin/cat', 22:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:26 INFO - 'PWD': '/builds/slave/test', 22:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:26 INFO - 'SHELL': '/bin/bash', 22:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:26 INFO - 'USER': 'cltbld', 22:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:26 INFO - 'XPC_FLAGS': '0x0', 22:27:26 INFO - 'XPC_SERVICE_NAME': '0', 22:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:26 INFO - Downloading/unpacking jsonschema==2.5.1 22:27:26 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 22:27:26 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 22:27:26 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 22:27:26 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 22:27:26 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 22:27:26 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 22:27:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:27:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:27:29 INFO - warning: no files found matching '*.txt' 22:27:29 INFO - no previously-included directories found matching 'build' 22:27:29 INFO - no previously-included directories found matching 'dist' 22:27:29 INFO - no previously-included directories found matching '.git*' 22:27:29 INFO - Installing collected packages: jsonschema, functools32 22:27:29 INFO - Running setup.py install for functools32 22:27:29 INFO - warning: no files found matching '*.txt' 22:27:29 INFO - no previously-included directories found matching 'build' 22:27:29 INFO - no previously-included directories found matching 'dist' 22:27:29 INFO - no previously-included directories found matching '.git*' 22:27:29 INFO - Successfully installed jsonschema functools32 22:27:29 INFO - Cleaning up... 22:27:29 INFO - Return code: 0 22:27:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:29 INFO - 'HOME': '/Users/cltbld', 22:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:29 INFO - 'LOGNAME': 'cltbld', 22:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:29 INFO - 'MOZ_NO_REMOTE': '1', 22:27:29 INFO - 'NO_EM_RESTART': '1', 22:27:29 INFO - 'PAGER': '/bin/cat', 22:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:29 INFO - 'PWD': '/builds/slave/test', 22:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:29 INFO - 'SHELL': '/bin/bash', 22:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:29 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:29 INFO - 'USER': 'cltbld', 22:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:29 INFO - 'XPC_FLAGS': '0x0', 22:27:29 INFO - 'XPC_SERVICE_NAME': '0', 22:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:27:29 INFO - Cleaning up... 22:27:29 INFO - Return code: 0 22:27:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:29 INFO - 'HOME': '/Users/cltbld', 22:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:29 INFO - 'LOGNAME': 'cltbld', 22:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:29 INFO - 'MOZ_NO_REMOTE': '1', 22:27:29 INFO - 'NO_EM_RESTART': '1', 22:27:29 INFO - 'PAGER': '/bin/cat', 22:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:29 INFO - 'PWD': '/builds/slave/test', 22:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:29 INFO - 'SHELL': '/bin/bash', 22:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:29 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:29 INFO - 'USER': 'cltbld', 22:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:29 INFO - 'XPC_FLAGS': '0x0', 22:27:29 INFO - 'XPC_SERVICE_NAME': '0', 22:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:30 INFO - Downloading/unpacking blobuploader==1.2.4 22:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:32 INFO - Downloading blobuploader-1.2.4.tar.gz 22:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:27:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:27:32 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 22:27:32 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 22:27:32 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 22:27:32 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 22:27:32 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 22:27:32 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 22:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:27:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:27:33 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 22:27:33 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 22:27:33 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 22:27:33 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 22:27:33 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 22:27:33 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 22:27:33 INFO - Downloading docopt-0.6.1.tar.gz 22:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:27:33 INFO - Installing collected packages: blobuploader, requests, docopt 22:27:33 INFO - Running setup.py install for blobuploader 22:27:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:27:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:27:34 INFO - Running setup.py install for requests 22:27:34 INFO - Running setup.py install for docopt 22:27:34 INFO - Successfully installed blobuploader requests docopt 22:27:34 INFO - Cleaning up... 22:27:34 INFO - Return code: 0 22:27:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:34 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:34 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 22:27:34 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 22:27:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:34 INFO - 'HOME': '/Users/cltbld', 22:27:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:34 INFO - 'LOGNAME': 'cltbld', 22:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:34 INFO - 'MOZ_NO_REMOTE': '1', 22:27:34 INFO - 'NO_EM_RESTART': '1', 22:27:34 INFO - 'PAGER': '/bin/cat', 22:27:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:34 INFO - 'PWD': '/builds/slave/test', 22:27:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:34 INFO - 'SHELL': '/bin/bash', 22:27:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:34 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:34 INFO - 'USER': 'cltbld', 22:27:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:34 INFO - 'XPC_FLAGS': '0x0', 22:27:34 INFO - 'XPC_SERVICE_NAME': '0', 22:27:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TAyTpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NkdEsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BDM6YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fBtOTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SKZ9K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Xp_oik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cCPIIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-WSbVIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ML7dLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3qDdSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4RCWs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hBqB76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bDmQ5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6IWUBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-K5aEBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-X98UT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JZEbjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:27:36 INFO - Running setup.py install for manifestparser 22:27:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:27:37 INFO - Running setup.py install for mozcrash 22:27:37 INFO - Running setup.py install for mozdebug 22:27:37 INFO - Running setup.py install for mozdevice 22:27:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:27:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:27:37 INFO - Running setup.py install for mozfile 22:27:37 INFO - Running setup.py install for mozhttpd 22:27:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:27:37 INFO - Running setup.py install for mozinfo 22:27:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:27:37 INFO - Running setup.py install for mozInstall 22:27:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Running setup.py install for mozleak 22:27:38 INFO - Running setup.py install for mozlog 22:27:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Running setup.py install for moznetwork 22:27:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Running setup.py install for mozprocess 22:27:38 INFO - Running setup.py install for mozprofile 22:27:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Running setup.py install for mozrunner 22:27:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:27:38 INFO - Running setup.py install for mozscreenshot 22:27:39 INFO - Running setup.py install for moztest 22:27:39 INFO - Running setup.py install for mozversion 22:27:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:27:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:27:39 INFO - Cleaning up... 22:27:39 INFO - Return code: 0 22:27:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:39 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:39 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 22:27:39 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 22:27:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:39 INFO - 'HOME': '/Users/cltbld', 22:27:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:39 INFO - 'LOGNAME': 'cltbld', 22:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:39 INFO - 'MOZ_NO_REMOTE': '1', 22:27:39 INFO - 'NO_EM_RESTART': '1', 22:27:39 INFO - 'PAGER': '/bin/cat', 22:27:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:39 INFO - 'PWD': '/builds/slave/test', 22:27:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:39 INFO - 'SHELL': '/bin/bash', 22:27:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:39 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:39 INFO - 'USER': 'cltbld', 22:27:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:39 INFO - 'XPC_FLAGS': '0x0', 22:27:39 INFO - 'XPC_SERVICE_NAME': '0', 22:27:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-s62Whe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:39 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)) 22:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TRI0KY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:39 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)) 22:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SPjlpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-t5oC7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b2RTBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sjWaK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IwslA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-lR8DaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KyrfRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-s5jxlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7DM6NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:40 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)) 22:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sanOtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:41 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)) 22:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rodATs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:41 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)) 22:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LNLxCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:41 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)) 22:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jFpMjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:41 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)) 22:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-98oMak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:41 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)) 22:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-gNu0To-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:41 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)) 22:27:41 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)) 22:27:41 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)) 22:27:41 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)) 22:27:41 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)) 22:27:41 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)) 22:27:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:44 INFO - Downloading blessings-1.6.tar.gz 22:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:27:44 INFO - Installing collected packages: blessings 22:27:44 INFO - Running setup.py install for blessings 22:27:44 INFO - Successfully installed blessings 22:27:44 INFO - Cleaning up... 22:27:44 INFO - Return code: 0 22:27:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:27:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:44 INFO - Reading from file tmpfile_stdout 22:27:44 INFO - Current package versions: 22:27:44 INFO - blessings == 1.6 22:27:44 INFO - blobuploader == 1.2.4 22:27:44 INFO - docopt == 0.6.1 22:27:44 INFO - functools32 == 3.2.3-2 22:27:44 INFO - jsonschema == 2.5.1 22:27:44 INFO - manifestparser == 1.1 22:27:44 INFO - mozInstall == 1.12 22:27:44 INFO - mozcrash == 0.17 22:27:44 INFO - mozdebug == 0.1 22:27:44 INFO - mozdevice == 0.48 22:27:44 INFO - mozfile == 1.2 22:27:44 INFO - mozhttpd == 0.7 22:27:44 INFO - mozinfo == 0.9 22:27:44 INFO - mozleak == 0.1 22:27:44 INFO - mozlog == 3.4 22:27:44 INFO - moznetwork == 0.27 22:27:44 INFO - mozprocess == 0.23 22:27:44 INFO - mozprofile == 0.28 22:27:44 INFO - mozrunner == 6.12 22:27:44 INFO - mozscreenshot == 0.1 22:27:44 INFO - mozsystemmonitor == 0.3 22:27:44 INFO - moztest == 0.7 22:27:44 INFO - mozversion == 1.4 22:27:44 INFO - psutil == 3.1.1 22:27:44 INFO - requests == 1.2.3 22:27:44 INFO - wsgiref == 0.1.2 22:27:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27: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')]} 22:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27: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')]} 22:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:44 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:44 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 22:27:44 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 22:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:44 INFO - 'HOME': '/Users/cltbld', 22:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:44 INFO - 'LOGNAME': 'cltbld', 22:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:44 INFO - 'MOZ_NO_REMOTE': '1', 22:27:44 INFO - 'NO_EM_RESTART': '1', 22:27:44 INFO - 'PAGER': '/bin/cat', 22:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:44 INFO - 'PWD': '/builds/slave/test', 22:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:44 INFO - 'SHELL': '/bin/bash', 22:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:44 INFO - 'USER': 'cltbld', 22:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:44 INFO - 'XPC_FLAGS': '0x0', 22:27:44 INFO - 'XPC_SERVICE_NAME': '0', 22:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 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)) 22:27:44 INFO - Cleaning up... 22:27:45 INFO - Return code: 0 22:27:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:27:45 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')]} 22:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:45 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')]} 22:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:45 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 0x10ea1d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff13a701d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, '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.yjjg5ld2XQ/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.occbHFdyhI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 22:27:45 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 22:27:45 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 22:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:45 INFO - 'HOME': '/Users/cltbld', 22:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:45 INFO - 'LOGNAME': 'cltbld', 22:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:45 INFO - 'MOZ_NO_REMOTE': '1', 22:27:45 INFO - 'NO_EM_RESTART': '1', 22:27:45 INFO - 'PAGER': '/bin/cat', 22:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:45 INFO - 'PWD': '/builds/slave/test', 22:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:45 INFO - 'SHELL': '/bin/bash', 22:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:27:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:45 INFO - 'USER': 'cltbld', 22:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:45 INFO - 'XPC_FLAGS': '0x0', 22:27:45 INFO - 'XPC_SERVICE_NAME': '0', 22:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:27:45 INFO - Cleaning up... 22:27:45 INFO - Return code: 0 22:27:45 INFO - Running post-action listener: _resource_record_post_action 22:27:45 INFO - Running post-action listener: _start_resource_monitoring 22:27:45 INFO - Starting resource monitoring. 22:27:45 INFO - [mozharness: 2016-10-11 05:27:45.299054Z] Finished create-virtualenv step (success) 22:27:45 INFO - [mozharness: 2016-10-11 05:27:45.300073Z] Running install step. 22:27:45 INFO - Running pre-action listener: _resource_record_pre_action 22:27:45 INFO - Running main action method: install 22:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:45 INFO - Reading from file tmpfile_stdout 22:27:45 INFO - Detecting whether we're running mozinstall >=1.0... 22:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:27:45 INFO - Reading from file tmpfile_stdout 22:27:45 INFO - Output received: 22:27:45 INFO - Usage: mozinstall [options] installer 22:27:45 INFO - Options: 22:27:45 INFO - -h, --help show this help message and exit 22:27:45 INFO - -d DEST, --destination=DEST 22:27:45 INFO - Directory to install application into. [default: 22:27:45 INFO - "/builds/slave/test"] 22:27:45 INFO - --app=APP Application being installed. [default: firefox] 22:27:45 INFO - mkdir: /builds/slave/test/build/application 22:27:45 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'] 22:27:45 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 22:28:19 INFO - Reading from file tmpfile_stdout 22:28:19 INFO - Output received: 22:28:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:28:19 INFO - Running post-action listener: _resource_record_post_action 22:28:19 INFO - [mozharness: 2016-10-11 05:28:19.473087Z] Finished install step (success) 22:28:19 INFO - [mozharness: 2016-10-11 05:28:19.473238Z] Running run-tests step. 22:28:19 INFO - Running pre-action listener: _resource_record_pre_action 22:28:19 INFO - Running main action method: run_tests 22:28:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:28:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:28:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:28:19 INFO - 2016-10-10 22:28:19.488 screenresolution[2294:290267] starting screenresolution argv=screenresolution get 22:28:19 INFO - 2016-10-10 22:28:19.490 screenresolution[2294:290267] Display 0: 1600x1200x32@60 22:28:19 INFO - 2016-10-10 22:28:19.496 screenresolution[2295:290269] starting screenresolution argv=screenresolution list 22:28:19 INFO - Available Modes on Display 0 22:28:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:28:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:28:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:28:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:28:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:28:19 INFO - Intel Iris: 22:28:19 INFO - Chipset Model: Intel Iris 22:28:19 INFO - Type: GPU 22:28:19 INFO - Bus: Built-In 22:28:19 INFO - VRAM (Dynamic, Max): 1536 MB 22:28:19 INFO - Vendor: Intel (0x8086) 22:28:19 INFO - Device ID: 0x0a2e 22:28:19 INFO - Revision ID: 0x0009 22:28:19 INFO - Displays: 22:28:19 INFO - Display: 22:28:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:28:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:28:19 INFO - Display Serial Number: 1600x1200 60 22:28:19 INFO - Main Display: Yes 22:28:19 INFO - Mirror: Off 22:28:19 INFO - Online: Yes 22:28:19 INFO - Rotation: Supported 22:28:19 INFO - Return code: 0 22:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:28:19 INFO - Python 2.7.3 22:28:19 INFO - Return code: 0 22:28:19 INFO - grabbing minidump binary from tooltool 22:28: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')]} 22:28:19 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 0x10e831d30>, '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 0x10e657db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e829b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:28:19 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 22:28:19 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 22:28:19 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 22:28:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:28:19 INFO - Return code: 0 22:28:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:28:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:28:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 22:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 22:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yjjg5ld2XQ/Render', 22:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:19 INFO - 'HOME': '/Users/cltbld', 22:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:19 INFO - 'LOGNAME': 'cltbld', 22:28:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:28:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:19 INFO - 'MOZ_NO_REMOTE': '1', 22:28:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:28:19 INFO - 'NO_EM_RESTART': '1', 22:28:19 INFO - 'PAGER': '/bin/cat', 22:28:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:19 INFO - 'PWD': '/builds/slave/test', 22:28:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:19 INFO - 'SHELL': '/bin/bash', 22:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.occbHFdyhI/Listeners', 22:28:19 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:19 INFO - 'USER': 'cltbld', 22:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:19 INFO - 'XPC_FLAGS': '0x0', 22:28:19 INFO - 'XPC_SERVICE_NAME': '0', 22:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 22:28:20 INFO - mozversion application_buildid: 20161010201346 22:28:20 INFO - mozversion application_changeset: 2976adccde9b75596c469428228177ed3d0f0b61 22:28:20 INFO - mozversion application_display_name: Nightly 22:28:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:28:20 INFO - mozversion application_name: Firefox 22:28:20 INFO - mozversion application_remotingname: firefox 22:28:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:28:20 INFO - mozversion application_vendor: Mozilla 22:28:20 INFO - mozversion application_version: 52.0a1 22:28:20 INFO - mozversion platform_buildid: 20161010201346 22:28:20 INFO - mozversion platform_changeset: 2976adccde9b75596c469428228177ed3d0f0b61 22:28:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:28:20 INFO - mozversion platform_version: 52.0a1 22:28:20 INFO - using testdate: 1476163700 22:28:20 INFO - actual date: 1476163700 22:28:20 INFO - starting webserver on 'localhost:53187' 22:28:20 INFO - SUITE-START | Running 2 tests 22:28:20 INFO - TEST-START | dromaeo_css 22:28:20 INFO - operating with platform_type : mac_ 22:28:20 INFO - Initialising browser for dromaeo_css test... 22:28:20 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpjGyveH/profile http://localhost:53187/getInfo.html) 22:28:22 INFO - PROCESS | 2305 | __metrics Screen width/height:1600/1200 22:28:22 INFO - PROCESS | 2305 | colorDepth:24 22:28:22 INFO - PROCESS | 2305 | Browser inner width/height: 1024/689 22:28:22 INFO - PROCESS | 2305 | __metrics 22:28:22 INFO - PROCESS | 2305 | 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" 22:28:22 INFO - PROCESS | 2305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:28:23 INFO - TEST-INFO | 2305: exit 0 22:28:23 INFO - Browser initialized. 22:28:23 INFO - Running cycle 1/1 for dromaeo_css test... 22:28:23 INFO - TEST-INFO | started process 2307 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpjGyveH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:35 INFO - PROCESS | 2307 | RSS: Main: 234950656 22:28:35 INFO - PROCESS | 2307 | 22:29:26 INFO - PROCESS | 2307 | 1476163766621 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] 22:31:39 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53187/tests/dromaeo/cssquery-ext.html) 22:31:40 INFO - PROCESS | 2307 | RSS: Main: 373805056 22:31:40 INFO - PROCESS | 2307 | 22:34:43 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-ext.html (next: http://localhost:53187/tests/dromaeo/cssquery-jquery.html) 22:34:44 INFO - PROCESS | 2307 | RSS: Main: 419487744 22:34:44 INFO - PROCESS | 2307 | 22:37:46 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53187/tests/dromaeo/cssquery-mootools.html) 22:37:47 INFO - PROCESS | 2307 | RSS: Main: 352198656 22:37:47 INFO - PROCESS | 2307 | 22:40:49 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53187/tests/dromaeo/cssquery-prototype.html) 22:40:50 INFO - PROCESS | 2307 | RSS: Main: 342134784 22:40:50 INFO - PROCESS | 2307 | 22:43:53 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53187/tests/dromaeo/cssquery-yui.html) 22:43:54 INFO - PROCESS | 2307 | RSS: Main: 332746752 22:43:54 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53187/tests/dromaeo/cssquery-yui.html (next: http://localhost:53187/tests/dromaeo/cssquery-dojo.html) 22:46:57 INFO - PROCESS | 2307 | RSS: Main: 458416128 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | __start_tp_report 22:46:57 INFO - PROCESS | 2307 | _x_x_mozilla_page_load 22:46:57 INFO - PROCESS | 2307 | _x_x_mozilla_page_load_details 22:46:57 INFO - PROCESS | 2307 | |i|pagename|runs| 22:46:57 INFO - PROCESS | 2307 | |0;dojo.html;2956.71911168439;3264.4287249731306;3275.2794385235247;3301.6368199498065;3345.5650765400496;5524.972375138129;5618.15727640854;5655.066913959197;5713.628614140083;5729.28383952006;5615.831525054255;5648.068068768648;5669.688167150805;5670.716464176795;5677.630953987988;5115.18157094864;5156.948430515685;5161.329027226474;5167.302414174094;5191.247269145972;5252.107141785906;5261.1319132343115;5261.39493958194;5267.683938963655;5269.946010797837;5131.230315452674;5174.353205849269;5208.140656791644;5218.947810521903;5260.342457192851;5245.947540524584;5246.606504512139;5249.711265880375;5268.420473747885;5349.946500535023;5054.570361519275;5078.060558796635;5135.897282054375;5142.382914050338;5176.094183517869;5592.664440133605;5640.971795140998;5652.5195358394585;5693.544516438675;5718.857028574277;6212.968935155341;6213.409726076061;6215.720292586844;6221.346758590328;6225.408586184305;6131.448169664752;6156.830202261556;6188.938110618881;6266.404691554295;6283.811485655392;5714.685692286923;5746.482816546531;5752.654840709571;5775.913361299584;5821.243238378984;6218.7166703871635;6268.373162683786;6276.748930042839;6295.276043254993;6301.4328710416285;5947.137665038632;6096.055111457731;6106.5659535032355;6121.418465245794;6141.60477413809;6102.50984716401;6107.606420791524;6141.004173643583;6215.689215539205;6247.281562620356;5144.742762861842;6172.6913654318005;6173.783917562953;6174.9073763893575;6191.597546159485;6194.628322300677;6206.875862482725;6210.099535567318;6215.471684906744;6232.065190221503;4704.270838020044;4853.854384368475;4857.149998750205;4858.489858564871;4879.414470263591;4365.432493775789;4365.738055716667;4404.603585677304;4432.3794668746405;4448.132614140211;5673.744681489343;5711.400302968129;5747.252857128547;5767;5794.710264486759;6179.721912513947;6240.375962403723;6247.9062814057825;6284.748610055546;6286.9056964145575;5609.0184217761725;5660.547156227574;5675.404082571353;5717;5735.369109397963;5356.758945847446;5372.946270537323;5374.435684253175;5377.220303056035;5379.811706590251;6217.285012223561;6259.561830671809;6266.185395898595;6272.278687950761;6275.058741188767;6182.598131121463;6228.937710622836;6244.500439964882;6272.560920735505;6277.027060805583;6259.937400625936;6276.089966954857;6297.622142671455;6301;6302.779402720883;6038.245219347582;6086.786962456293;6105.672036201006;6109.3890610940325;6135.785247516494;6186.381361863958;6195.349488303844;6219.595726277777;6248.9062664059165;6261.530385221035;5112.207607820794;6071.574989750675;6104.969475152773;6110.419510146707;6127.785527506516;5909.23179986599;5957.970210148921;6076.3012253591405;6087.4521293083835;6098.115773212948;5841.970790146022;5844.26946631671;5892.469677729025;5909.113632955091;5956.8808623828145;6057.666828324484;6072.332043475126;6078.817635470943;6087.421694938974;6094.78668246627;5757.769689212385;5854.385289544536;5882.470577648032;5905.055191169392;5966.8209953699725 22:46:57 INFO - PROCESS | 2307 | |1;ext.html;473.55249289421465;510.9856933986039;512.369785164248;517.5342192027175;535.1170568561872;28973.420531589356;30123.54814677779;30800.38399232014;31244.99999999997;31345.216369590773;31529.369412611733;32195.517067243953;32221.677783222218;33240.67315287931;42004.739857804314;9495.335326527129;9672.226221902267;9720.173785228264;9725.319227654067;9743;9516.048395160498;9626.855597166048;9630.3258771886;9632.373895696757;9652.758681032996;9619.855702164474;9659.275554333419;9661.855072173923;9668.129868311851;9673;9572.28207884408;9613.182879455255;9616.855747163798;9645.565949532252;9649.179819715333;9532.95233523835;9596.32825702201;9606.855897161548;9614.51927403634;9639.614415423375;39162.21675566501;39325.60674393277;39427.40858887119;39479.210415791524;39484;3235.1912021994503;3286;3324.4182268102986;3402.4688889999597;3436.2096717755057;30363.696363036306;31230;31271;31375.05874823759;31690.207744806336;3148.102790704638;3160.7945483543494;3320.7841490303053;3387.7797943133864;3399.2351720862857;443575;448870;449819;450754;461822;55789.72105139448;56220;56266.21737152388;56284;56295.87408251812;57541.424585754445;57717;57742;57762.71118644464;57885.71057144687;15553.22233888301;15768.842311576967;15784.92107539455;15792.842071579367;15799.131047792482;12396.132270740962;12437.378131093408;12452.252864828139;12455.750884982432;12466.252024878537;14051.648708782157;14570.708585828224;14677.55967320982;14684.559463216121;14828.481003164838;6009.639421634716;6104.511639068953;6208.4722798561725;6248.937510624926;6278.466330361879;727.3490226247479;730.8778489320238;759.2597217712686;798.62863768348;885.3050355470924;23246.65130023051;26728.064517741786;29295.707042929724;29649.703502965127;29895.953641622873;929.5398777604994;1045.5608644369383;1059.6873922192817;1114.8216285394299;1210.1831263896834;14613.634659133606;15104.242163718394;15171.468998584996;15311.194674021976;15339.156346401054;608864;611970;612334.2599444047;612636;614377;56833.43166568364;57056;57122;57278.427215728145;57323;1345.7577636025414;1385.8129152564275;1397.657573894382;1400.208881981677;1402.2988505747126;28457;28681.996130135347;28981;29403.264918377212;29545.704542954296;47266;47390.81522961953;47678;47722;47733.52266477291;551412;554040.2297988484;554709;555135;555729;37809;38400.847974560806;38552;38841.6115838838;38943.80528097455;35642.18830457855;45441.772791137155;45602;46109.07781844277;47047.294290584985;38282;38526.229475409775;39049.58057968977;39116.02209944774;39573.60426395814;38029.61970380261;38485;38675.61324386833;39478.01304967399;39610.2077958445;31852.885149019672;32410.83794581012;32749;36770.632293677794;36952.445713313784;27355.13642262882;28972.27569310784;32668;33062.33875322528;34067.65932340744;19735.21059157675;20873.582528349652;25154.62268065944;25217.49565008653;25657.845396957557 22:46:57 INFO - PROCESS | 2307 | |2;jquery.html;336.7810922900115;362.7678285897026;368.8395547936647;386.46088706254756;394.5657534860782;19434;20067.197312107517;21276;21531.892340538274;21564.35306940794;20424.183032678677;20655.27706530268;21041.26355577555;21397.358079257647;21425.250116245894;4542.977285113569;5381.488758567949;5868.119782032687;6104.328523862371;6105.908411373833;6119.571629985903;6160.7535698572;6172;6182;6185.381461853801;6065.211522502069;6171.660558669271;6727.024581435713;6802.38778509934;6829.931700683004;5777.422257774189;6753.594784312957;6801.863962720718;6810.3189681031745;6821.386075253251;5804.332501762267;5947.970260148715;5951.196588460546;5953.3748956359395;5955.34491205967;10864;13259.801102983463;13339.666508337272;13378.598642040657;13583.185008899498;2046.1815273890563;2129.6379615465407;2319.7100362454694;2337.7077865266842;2367.4791545859885;9795.265355098325;9807.607695692164;9840.507974601242;10306.79386412275;10502.317349372266;1810.6469238498498;1826.3881599663996;1860.9720854187062;1868.277033402533;1886.877352972066;108475;115478;117303.41348293204;117540.41229793796;119066.40466797697;9953.751156221007;10511.10655594283;11189.944050279697;11295.661130166109;11754.35351055683;14136.15183089018;14139.787903181461;14183.929080354532;14184.716305674036;14217.64455888611;8081.797955051171;8229.46508476947;8242.464239824512;8250;8415.495070295801;8138.470999385021;8148.796280093045;8301.169883011771;8332.416730828783;8364.832703345899;3564.8752293669595;4413.823447062082;4418.911621767546;4495.57292057254;4501.7974191161475;1685.1405783050732;1813.7551430556705;1901.8858868467937;1917.1756144857845;1936.2642195965443;801.8235988082613;866.9826603468022;904.6749852878172;918.3020904112789;987.1757082835769;15524.611884702746;20167;20330.390088297376;20537.25433338153;21179.258725944826;855.3328403845011;936.0358830404695;937.6098374776618;975.3806332978468;980.7302991677345;10897.91020897904;11220;11378.374189419495;11406.714832129263;11419.942900285612;576454;579677;582514;591979;592532;136252.31873840568;141456;143376;144311;145610;1015.8527013582989;1018.5060245780791;1042.5777560088025;1042.61944390297;1044.300318786415;18702.438926832216;18838.52903677419;19091.890595009463;19117.90441047786;19141.330053448062;54115.72942135264;55207;55690;56001.43998559962;56990;83286.58356708178;84870;85195.5740221295;85486.71769923331;86784.18942295224;7856.84286314282;8021.438499304993;8120.14738452454;8138.633761480866;8183.304419124441;11083;13006.739865202453;13007.219566826021;13260.336983150997;13296.534621288209;11218;11257.268277561932;11325.15061370417;11398.08815294791;11401.20191586581;8184.8772268417215;10896.673099807018;11091.389973551532;11408.315501069963;11529.423528823358;11064.170187235828;11227.887721122685;11310.982011618993;11394.25937314072;11737.35444550558;6222.844428889314;8126.959365203136;8130.796730081795;8204.917950820416;8402.957985210034;10753.89246107529;11273.830892536454;11331.250248775877;11497.482613282255;11534.711632209262 22:46:57 INFO - PROCESS | 2307 | |3;mootools.html;1331.7136815584647;1505.932233049513;1506.1716056169105;1730.2646375290499;1926.0947354743266;7494.437917156215;7934.2065793420625;7942.92057079429;7959.965204523411;8025.478343907642;8102.837943241132;8314.542700151489;8315.501069935808;8336.333093352532;8339.54132522711;2259.830512711549;2299.5515874404496;2303.608386574278;2304.1704986205036;2307.7346105197867;2248.7076680031573;2279.31620513846;2300.574393737156;2301.390131615123;2308.6190778521523;2207.073029327686;2229.9331020069426;2242.125571027301;2247.674087257347;2260.0959616153505;2241.5628952354296;2272.8067764423727;2274.283600665785;2275.6472746724203;2283.212291759357;2262.9547409051893;2264.52444986553;2264.52444986553;2267.977320226793;2281.555096756133;8310.335173186131;8334.874976875351;8335.374846886507;8338.124496927796;8374.706885259047;4896.485868983741;4934.432540257844;5112.437631860493;5162.690238585659;5177.741112944375;3326.151831283007;3490.7207423406066;3566.964330356689;3567.1438854674952;3571.8571257149683;1046.9476526173737;1063.3301020357203;1064.0689396777818;1084.2572837606185;1084.5878566144893;10615.734606634818;10730.892691073068;10843.891561084367;10854.077403420719;10872.967068128515;4391.363252328394;4401.185780630593;4585.28928016158;4592.977035114803;4597.885052873705;9571.042895710374;9598.184154346958;9602.279829012852;9608.327417080738;9639.421634701801;5154.278401023859;5158.123119069767;5162.81255311273;5169.06956747789;5169.534741873174;3880.534335879656;4218.535961044283;4278.486581610165;4380.386745855582;4654.720716757005;3579.51676523666;3581.1047238190454;3778.8852288574935;3893.4354518594646;3930.4104384342577;1047.5652604169336;1221.712897469094;1234.4074844074714;1256.8177614246065;1257.7421628566121;794.2811755361291;864.1401805203736;908.6274627402865;917.5962576466334;948.83869742144;5423.213634023124;5540.335159780771;5741.253637027244;5800.709964501758;5993.190919225936;950.8906475755239;957.712686194139;973.9610415583297;974.551706215149;1014.2291858187683;2843.516602177635;3775.6413140751583;3868.7872167031082;3977.1449138434796;3997.340438827619;9787.804243915081;10249;10256.743581410525;10272;10290.691279261635;8594.269487093543;10002;10214.285000050073;10228.53971571281;10239;7653.808654783564;7867.370610351159;7876.803079922933;7876.842463150705;7880.330171935335;5576.414476479911;5630.605857590093;5729.427057294237;5857.5313974881055;5992.250968628921;8145.674173033012;9371.156595906401;9404.623815047322;9470.905290947003;9561.760955976157;7977.880331794911;8689.522076285864;9076.31927605419;9462.2430205582;9489.335746497678;6700.095487109178;6872.518923675295;6900.137482814648;6918.688659010458;6919.273476285119;7692.499987500794;7912.683492660229;7925.564093974886;8034.196580342153;8086.595670216583;7165.964170179324;7229.7469588566255;7294.1247050354295;7332.41340692754;7374.299441553044;7104.431645468453;7117.1459424869345;7145.535540189871;7205.351518363373;7261.745838895613;6633.170853643294;6637.137172167781;6643.3356664335115;6731.596104233761;6741.426978706865;4793.400824896888;4894.804207831789;4900.187817284126;4941.481144479778;5022.799088036438;4794.184988551885;4916.803327866988;4968.329275547755;4981.825636102719;5065.670731402447 22:46:57 INFO - PROCESS | 2307 | |4;prototype.html;300.27932960893855;301.663645035785;310.16410772966856;315.7773769492508;317.83472594250975;5141.6914985100875;5153.56194723449;5170.682480129092;5181.4300426953005;5183.766730497127;4771.90456190876;4959.008198360324;4976.950230497694;4985.750712464372;5103.41310749263;3756;3882.631594727207;3921.607839216084;3989.4813674222455;4001.9599804001878;3928.17508323251;3952.3873799561115;3974.1455587048777;4003.3194356959243;4036.8587099451524;3522.4892390603477;3652.3476523476525;3825.445310429986;3962.9207415851665;3980.2039592081555;3903.3559462688904;3930.78380689062;3975.8807235782974;3986.900327491807;3988.4416181734728;3465.4455287153933;3467.7572569920117;3469.9132521686906;3488.354654388946;3498.265364273506;5022.773975171126;5023.095842748304;5031.396232452093;5033.471485494044;5039.916417970117;4046.3121269383983;4394.1651086293505;4398.032432864765;4416.116776644684;4430.6676999224865;5299.49654782795;5309.106536937378;5330.387005494379;5354.14333706609;5376.198973343934;3232.9676703232903;3678.062094165997;3682.723795715332;3687.94468082979;3688.963110368889;4288.499025341137;5470.17947307903;5477.794885125266;5570.248016517799;5594.4964953154;3271.8691252350113;3691.852325906934;3698;3707.629237076271;3722.4974628424816;3582.964170358263;3732.011017080476;3743.989122936792;3744.884140460631;3755.5681096673684;3673.504076949631;3903.8048097595006;4152.283556996923;4402.7358358498595;4410.80151393188;4766.523347665206;5351.919721204107;5359.866003349869;5400.081986062379;5403.297961142228;1036.79782442423;1261.4764872577962;1275.2476039136955;1285.9871401285868;1286.1832736212573;813.5850716134696;829.4940086547201;838.3335248477475;853.2534032721369;876.8684697295329;4628.490866004737;4669.976650116728;5028.396592408934;5063.367079115111;5084;4501.257292546758;4850.563449289581;5062.417821950449;5503.422140675251;5615.887682246333;4117.826419470496;4691.83578574755;5050.419201791841;5110.901156251423;5111.233315002779;2789.5257806172594;2894.580285858539;2930.326025014216;2934.8385838779072;2949.483840327934;5713.914291285634;5738.799142030009;5770.278715160605;5778.8266352009505;5798.10129429939;5066.873328166752;5137.743112844343;5667.271482197969;5728.250439788924;5744.253247077937;4737.881552961135;4744.715317080986;5352.74210560518;5477.452254774491;5828.912566311424;5255.63210575256;5276.604254680838;5554.333479982428;5607;5677.971610142088;5451.972740136274;5479.726013699219;5486.299247233371;5522.116461366162;5524.889502210013;4536.750478723702;5710.972025035533;5746.856328591819;5821.592488525763;5854.331515518057;3127.9374412511165;3585.9103522412147;3626.8549258029384;3692.630736926286;3749.6812770914776;4801.303810947463;4916.901661966669;5473.562115030868;5519.889602207853;5543.503254121284;5369.19462080691;5378.623496355218;5461.590380721541;5489.231507589021;5499.3675727291875;4723.8114272865;4870.391201099863;5213.817516386908;5363;5404.811051524139;4793.736344500946;4886;4999.450060493416;5071.163258062378;5294.729264976301;5287.656302340336;5345.65253258537;5368.570514358764;5435.891282174255;5450.400455949922;4648.093621743727;5044.873878153076;5333.7866485340155;5351.518363347317;5358.544523715528 22:46:57 INFO - PROCESS | 2307 | |5;yui.html;713.5825542057896;721.592300350302;771.0593076446733;797.3660939553055;816.4448175240835;15486.767698484531;15659;15765;15783.21083945804;15821.446249381273;14111.364988575513;14217.92891035546;14230.71538569228;14253.429862805475;14261.286935653201;673.5318953327439;699.0143897105083;700.1318365227132;850.5492089192707;877.806882485852;851.531657588327;873.2315562305163;878.1131057631806;878.7275944457239;880.9779755506099;933.4166146158651;934.8364036293622;936.2416442681416;937.4562753602894;937.4843835890233;725.4522835259398;748.4685873030155;903.602414937432;929.6978481993345;931.7950050988771;698.559907258429;698.9231184569693;700.3276854219955;703.5673061067407;703.9753608623673;15555.22223888801;15597.986130901569;15600.141992190425;15616.531504054896;15628.765568516481;14133.929330353283;14302.14187148764;14320.283985800668;14331.498397556035;14447.916406269467;10642.893571064267;12723.809142862865;12839.935800321033;12880.42038108278;13412;4597.37935378723;4603.930941035887;4729.337892694991;4802.289463581459;4847.006363695434;115529;117085.36432945881;118908;122579;122680.77319226782;11058.889411105947;11254.720228266924;11342.092632589372;11483.023942964774;11566.958973692408;22592.548149037255;22596.322110336718;22606;22610.869456527107;22633.886830566073;6458.903116453257;7740.303372696371;7830.373570114377;7978.5611791350875;8039.758807235792;4126.360414135814;4295.871123866289;4298.4197133387215;4329.891752706145;4374.147041326974;3327.450970589874;3477.4783782432332;3484.790912545255;3487.1805125795418;3489.5114683944266;626.3830127324673;626.8045307603107;645.8062581225613;646.3762469217228;647.0811447744214;555.8526990347561;600.8738164985315;632.509804901196;643.4948565376158;644.5971267957526;8701.825963480695;8992.190702836777;9170.541472926327;9232.584533696;9308.348415610977;552.6838966202783;570.120928764471;621.18314416543;631.4632562322081;645.6513482719279;4104.281750693616;4392.538783427758;4480.215962206601;4529.230030894756;4607.746573938399;98189;134369;155598;163929;166440;120976;142658.28670856787;143418;145507.272463637;146336;96118;116683.41658291825;118536;119837;121132;8519.105493923049;8520.74437766868;8580.227779499875;8755.649774008969;8828.602712878064;66677;66692.666536667;67256;68447.94145288029;69279.3072069293;95172;99829;102766;103030.45454318041;105380;11724.472398741906;12059.457324420247;12071.939640301742;12088.576899808464;12308.630741077783;11996.100292477924;12012.05389385621;12308.876911231131;12442.191257568229;12809.487620495449;11614.941925290319;11995.340256285988;12026.27842329463;12314.445849936954;12352.691182720504;11809.23239989398;11821.349825759666;11877.643670689547;11958.521659133537;11990.28058316469;11720.296782193096;11730.150081727876;11951.521939122338;11976.58081967127;12000.219985700902;9307.627694892128;9854.568054692356;9877.40735555869;10076.798464030531;10263.640772572924;9802.460864652512;10032.398056116657;10057.346272492265;10082.445465499175;10129.493525323851 22:46:57 INFO - PROCESS | 2307 | __end_tp_report 22:46:57 INFO - PROCESS | 2307 | __start_cc_report 22:46:57 INFO - PROCESS | 2307 | _x_x_mozilla_cycle_collect,1455 22:46:57 INFO - PROCESS | 2307 | __end_cc_report 22:46:57 INFO - PROCESS | 2307 | __startTimestamp1476164817804__endTimestamp 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | ------- Summary: start ------- 22:46:57 INFO - PROCESS | 2307 | Number of tests: 6 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#0] dojo.html Cycles:165 Average:5704.14 Median:5892.47 stddev:651.17 (11.1%) stddev-sans-first:616.47 22:46:57 INFO - PROCESS | 2307 | Values: 2956.7 3264.4 3275.3 3301.6 3345.6 5525.0 5618.2 5655.1 5713.6 5729.3 5615.8 5648.1 5669.7 5670.7 5677.6 5115.2 5156.9 5161.3 5167.3 5191.2 5252.1 5261.1 5261.4 5267.7 5269.9 5131.2 5174.4 5208.1 5218.9 5260.3 5245.9 5246.6 5249.7 5268.4 5349.9 5054.6 5078.1 5135.9 5142.4 5176.1 5592.7 5641.0 5652.5 5693.5 5718.9 6213.0 6213.4 6215.7 6221.3 6225.4 6131.4 6156.8 6188.9 6266.4 6283.8 5714.7 5746.5 5752.7 5775.9 5821.2 6218.7 6268.4 6276.7 6295.3 6301.4 5947.1 6096.1 6106.6 6121.4 6141.6 6102.5 6107.6 6141.0 6215.7 6247.3 5144.7 6172.7 6173.8 6174.9 6191.6 6194.6 6206.9 6210.1 6215.5 6232.1 4704.3 4853.9 4857.1 4858.5 4879.4 4365.4 4365.7 4404.6 4432.4 4448.1 5673.7 5711.4 5747.3 5767.0 5794.7 6179.7 6240.4 6247.9 6284.7 6286.9 5609.0 5660.5 5675.4 5717.0 5735.4 5356.8 5372.9 5374.4 5377.2 5379.8 6217.3 6259.6 6266.2 6272.3 6275.1 6182.6 6228.9 6244.5 6272.6 6277.0 6259.9 6276.1 6297.6 6301.0 6302.8 6038.2 6086.8 6105.7 6109.4 6135.8 6186.4 6195.3 6219.6 6248.9 6261.5 5112.2 6071.6 6105.0 6110.4 6127.8 5909.2 5958.0 6076.3 6087.5 6098.1 5842.0 5844.3 5892.5 5909.1 5956.9 6057.7 6072.3 6078.8 6087.4 6094.8 5757.8 5854.4 5882.5 5905.1 5966.8 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#1] ext.html Cycles:180 Average:66648.78 Median:39139.12 stddev:145129.17 (370.8%) stddev-sans-first:145451.25 22:46:57 INFO - PROCESS | 2307 | Values: 473.6 511.0 512.4 517.5 535.1 28973.4 30123.5 30800.4 31245.0 31345.2 31529.4 32195.5 32221.7 33240.7 42004.7 9495.3 9672.2 9720.2 9725.3 9743.0 9516.0 9626.9 9630.3 9632.4 9652.8 9619.9 9659.3 9661.9 9668.1 9673.0 9572.3 9613.2 9616.9 9645.6 9649.2 9533.0 9596.3 9606.9 9614.5 9639.6 39162.2 39325.6 39427.4 39479.2 39484.0 3235.2 3286.0 3324.4 3402.5 3436.2 30363.7 31230.0 31271.0 31375.1 31690.2 3148.1 3160.8 3320.8 3387.8 3399.2 443575.0 448870.0 449819.0 450754.0 461822.0 55789.7 56220.0 56266.2 56284.0 56295.9 57541.4 57717.0 57742.0 57762.7 57885.7 15553.2 15768.8 15784.9 15792.8 15799.1 12396.1 12437.4 12452.3 12455.8 12466.3 14051.6 14570.7 14677.6 14684.6 14828.5 6009.6 6104.5 6208.5 6248.9 6278.5 727.3 730.9 759.3 798.6 885.3 23246.7 26728.1 29295.7 29649.7 29896.0 929.5 1045.6 1059.7 1114.8 1210.2 14613.6 15104.2 15171.5 15311.2 15339.2 608864.0 611970.0 612334.3 612636.0 614377.0 56833.4 57056.0 57122.0 57278.4 57323.0 1345.8 1385.8 1397.7 1400.2 1402.3 28457.0 28682.0 28981.0 29403.3 29545.7 47266.0 47390.8 47678.0 47722.0 47733.5 551412.0 554040.2 554709.0 555135.0 555729.0 37809.0 38400.8 38552.0 38841.6 38943.8 35642.2 45441.8 45602.0 46109.1 47047.3 38282.0 38526.2 39049.6 39116.0 39573.6 38029.6 38485.0 38675.6 39478.0 39610.2 31852.9 32410.8 32749.0 36770.6 36952.4 27355.1 28972.3 32668.0 33062.3 34067.7 19735.2 20873.6 25154.6 25217.5 25657.8 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#2] jquery.html Cycles:180 Average:34907.48 Median:21548.12 stddev:98223.84 (455.8%) stddev-sans-first:98465.09 22:46:57 INFO - PROCESS | 2307 | Values: 336.8 362.8 368.8 386.5 394.6 19434.0 20067.2 21276.0 21531.9 21564.4 20424.2 20655.3 21041.3 21397.4 21425.3 4543.0 5381.5 5868.1 6104.3 6105.9 6119.6 6160.8 6172.0 6182.0 6185.4 6065.2 6171.7 6727.0 6802.4 6829.9 5777.4 6753.6 6801.9 6810.3 6821.4 5804.3 5948.0 5951.2 5953.4 5955.3 10864.0 13259.8 13339.7 13378.6 13583.2 2046.2 2129.6 2319.7 2337.7 2367.5 9795.3 9807.6 9840.5 10306.8 10502.3 1810.6 1826.4 1861.0 1868.3 1886.9 108475.0 115478.0 117303.4 117540.4 119066.4 9953.8 10511.1 11189.9 11295.7 11754.4 14136.2 14139.8 14183.9 14184.7 14217.6 8081.8 8229.5 8242.5 8250.0 8415.5 8138.5 8148.8 8301.2 8332.4 8364.8 3564.9 4413.8 4418.9 4495.6 4501.8 1685.1 1813.8 1901.9 1917.2 1936.3 801.8 867.0 904.7 918.3 987.2 15524.6 20167.0 20330.4 20537.3 21179.3 855.3 936.0 937.6 975.4 980.7 10897.9 11220.0 11378.4 11406.7 11419.9 576454.0 579677.0 582514.0 591979.0 592532.0 136252.3 141456.0 143376.0 144311.0 145610.0 1015.9 1018.5 1042.6 1042.6 1044.3 18702.4 18838.5 19091.9 19117.9 19141.3 54115.7 55207.0 55690.0 56001.4 56990.0 83286.6 84870.0 85195.6 85486.7 86784.2 7856.8 8021.4 8120.1 8138.6 8183.3 11083.0 13006.7 13007.2 13260.3 13296.5 11218.0 11257.3 11325.2 11398.1 11401.2 8184.9 10896.7 11091.4 11408.3 11529.4 11064.2 11227.9 11311.0 11394.3 11737.4 6222.8 8127.0 8130.8 8204.9 8403.0 10753.9 11273.8 11331.3 11497.5 11534.7 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#3] mootools.html Cycles:180 Average:5420.90 Median:5089.05 stddev:2967.71 (58.3%) stddev-sans-first:2960.13 22:46:57 INFO - PROCESS | 2307 | Values: 1331.7 1505.9 1506.2 1730.3 1926.1 7494.4 7934.2 7942.9 7960.0 8025.5 8102.8 8314.5 8315.5 8336.3 8339.5 2259.8 2299.6 2303.6 2304.2 2307.7 2248.7 2279.3 2300.6 2301.4 2308.6 2207.1 2229.9 2242.1 2247.7 2260.1 2241.6 2272.8 2274.3 2275.6 2283.2 2263.0 2264.5 2264.5 2268.0 2281.6 8310.3 8334.9 8335.4 8338.1 8374.7 4896.5 4934.4 5112.4 5162.7 5177.7 3326.2 3490.7 3567.0 3567.1 3571.9 1046.9 1063.3 1064.1 1084.3 1084.6 10615.7 10730.9 10843.9 10854.1 10873.0 4391.4 4401.2 4585.3 4593.0 4597.9 9571.0 9598.2 9602.3 9608.3 9639.4 5154.3 5158.1 5162.8 5169.1 5169.5 3880.5 4218.5 4278.5 4380.4 4654.7 3579.5 3581.1 3778.9 3893.4 3930.4 1047.6 1221.7 1234.4 1256.8 1257.7 794.3 864.1 908.6 917.6 948.8 5423.2 5540.3 5741.3 5800.7 5993.2 950.9 957.7 974.0 974.6 1014.2 2843.5 3775.6 3868.8 3977.1 3997.3 9787.8 10249.0 10256.7 10272.0 10290.7 8594.3 10002.0 10214.3 10228.5 10239.0 7653.8 7867.4 7876.8 7876.8 7880.3 5576.4 5630.6 5729.4 5857.5 5992.3 8145.7 9371.2 9404.6 9470.9 9561.8 7977.9 8689.5 9076.3 9462.2 9489.3 6700.1 6872.5 6900.1 6918.7 6919.3 7692.5 7912.7 7925.6 8034.2 8086.6 7166.0 7229.7 7294.1 7332.4 7374.3 7104.4 7117.1 7145.5 7205.4 7261.7 6633.2 6637.1 6643.3 6731.6 6741.4 4793.4 4894.8 4900.2 4941.5 5022.8 4794.2 4916.8 4968.3 4981.8 5065.7 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#4] prototype.html Cycles:180 Average:4373.96 Median:4981.35 stddev:1336.58 (26.8%) stddev-sans-first:1304.88 22:46:57 INFO - PROCESS | 2307 | Values: 300.3 301.7 310.2 315.8 317.8 5141.7 5153.6 5170.7 5181.4 5183.8 4771.9 4959.0 4977.0 4985.8 5103.4 3756.0 3882.6 3921.6 3989.5 4002.0 3928.2 3952.4 3974.1 4003.3 4036.9 3522.5 3652.3 3825.4 3962.9 3980.2 3903.4 3930.8 3975.9 3986.9 3988.4 3465.4 3467.8 3469.9 3488.4 3498.3 5022.8 5023.1 5031.4 5033.5 5039.9 4046.3 4394.2 4398.0 4416.1 4430.7 5299.5 5309.1 5330.4 5354.1 5376.2 3233.0 3678.1 3682.7 3687.9 3689.0 4288.5 5470.2 5477.8 5570.2 5594.5 3271.9 3691.9 3698.0 3707.6 3722.5 3583.0 3732.0 3744.0 3744.9 3755.6 3673.5 3903.8 4152.3 4402.7 4410.8 4766.5 5351.9 5359.9 5400.1 5403.3 1036.8 1261.5 1275.2 1286.0 1286.2 813.6 829.5 838.3 853.3 876.9 4628.5 4670.0 5028.4 5063.4 5084.0 4501.3 4850.6 5062.4 5503.4 5615.9 4117.8 4691.8 5050.4 5110.9 5111.2 2789.5 2894.6 2930.3 2934.8 2949.5 5713.9 5738.8 5770.3 5778.8 5798.1 5066.9 5137.7 5667.3 5728.3 5744.3 4737.9 4744.7 5352.7 5477.5 5828.9 5255.6 5276.6 5554.3 5607.0 5678.0 5452.0 5479.7 5486.3 5522.1 5524.9 4536.8 5711.0 5746.9 5821.6 5854.3 3127.9 3585.9 3626.9 3692.6 3749.7 4801.3 4916.9 5473.6 5519.9 5543.5 5369.2 5378.6 5461.6 5489.2 5499.4 4723.8 4870.4 5213.8 5363.0 5404.8 4793.7 4886.0 4999.5 5071.2 5294.7 5287.7 5345.7 5368.6 5435.9 5450.4 4648.1 5044.9 5333.8 5351.5 5358.5 22:46:57 INFO - PROCESS | 2307 | 22:46:57 INFO - PROCESS | 2307 | [#5] yui.html Cycles:180 Average:25597.75 Median:3488.35 stddev:41792.45 (1198.1%) stddev-sans-first:41867.92 22:46:57 INFO - PROCESS | 2307 | Values: 713.6 721.6 771.1 797.4 816.4 15486.8 15659.0 15765.0 15783.2 15821.4 14111.4 14217.9 14230.7 14253.4 14261.3 673.5 699.0 700.1 850.5 877.8 851.5 873.2 878.1 878.7 881.0 933.4 934.8 936.2 937.5 937.5 725.5 748.5 903.6 929.7 931.8 698.6 698.9 700.3 703.6 704.0 15555.2 15598.0 15600.1 15616.5 15628.8 14133.9 14302.1 14320.3 14331.5 14447.9 10642.9 12723.8 12839.9 12880.4 13412.0 4597.4 4603.9 4729.3 4802.3 4847.0 115529.0 117085.4 118908.0 122579.0 122680.8 11058.9 11254.7 11342.1 11483.0 11567.0 22592.5 22596.3 22606.0 22610.9 22633.9 6458.9 7740.3 7830.4 7978.6 8039.8 4126.4 4295.9 4298.4 4329.9 4374.1 3327.5 3477.5 3484.8 3487.2 3489.5 626.4 626.8 645.8 646.4 647.1 555.9 600.9 632.5 643.5 644.6 8701.8 8992.2 9170.5 9232.6 9308.3 552.7 570.1 621.2 631.5 645.7 4104.3 4392.5 4480.2 4529.2 4607.7 98189.0 134369.0 155598.0 163929.0 166440.0 120976.0 142658.3 143418.0 145507.3 146336.0 96118.0 116683.4 118536.0 119837.0 121132.0 8519.1 8520.7 8580.2 8755.6 8828.6 66677.0 66692.7 67256.0 68447.9 69279.3 95172.0 99829.0 102766.0 103030.5 105380.0 11724.5 12059.5 12071.9 12088.6 12308.6 11996.1 12012.1 12308.9 12442.2 12809.5 11614.9 11995.3 12026.3 12314.4 12352.7 11809.2 11821.3 11877.6 11958.5 11990.3 11720.3 11730.2 11951.5 11976.6 12000.2 9307.6 9854.6 9877.4 10076.8 10263.6 9802.5 10032.4 10057.3 10082.4 10129.5 22:46:57 INFO - PROCESS | 2307 | -------- Summary: end -------- 22:46:57 INFO - PROCESS | 2307 | 22:46:58 INFO - TEST-INFO | 2307: exit 0 22:46:58 INFO - TEST-OK | dromaeo_css | took 1118385ms 22:46:58 INFO - TEST-START | kraken 22:46:58 INFO - operating with platform_type : mac_ 22:46:58 INFO - Initialising browser for kraken test... 22:46:58 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmppbekdS/profile http://localhost:53187/getInfo.html) 22:47:00 INFO - PROCESS | 2311 | __metrics Screen width/height:1600/1200 22:47:00 INFO - PROCESS | 2311 | colorDepth:24 22:47:00 INFO - PROCESS | 2311 | Browser inner width/height: 1024/689 22:47:00 INFO - PROCESS | 2311 | __metrics 22:47:00 INFO - PROCESS | 2311 | 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" 22:47:00 INFO - PROCESS | 2311 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:47:01 INFO - TEST-INFO | 2311: exit 0 22:47:01 INFO - Browser initialized. 22:47:01 INFO - Running cycle 1/1 for kraken test... 22:47:01 INFO - TEST-INFO | started process 2312 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmppbekdS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:47:13 INFO - PROCESS | 2312 | RSS: Main: 235065344 22:47:13 INFO - PROCESS | 2312 | 22:47:16 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53187/tests/kraken/driver.html?testName=audio-beat-detection) 22:47:17 INFO - PROCESS | 2312 | RSS: Main: 293896192 22:47:17 INFO - PROCESS | 2312 | 22:47:19 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53187/tests/kraken/driver.html?testName=audio-dft) 22:47:20 INFO - PROCESS | 2312 | RSS: Main: 293740544 22:47:20 INFO - PROCESS | 2312 | 22:47:24 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53187/tests/kraken/driver.html?testName=audio-fft) 22:47:24 INFO - PROCESS | 2312 | RSS: Main: 383262720 22:47:24 INFO - PROCESS | 2312 | 22:47:26 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53187/tests/kraken/driver.html?testName=audio-oscillator) 22:47:27 INFO - PROCESS | 2312 | RSS: Main: 285745152 22:47:27 INFO - PROCESS | 2312 | 22:47:29 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53187/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:47:29 INFO - PROCESS | 2312 | RSS: Main: 289435648 22:47:29 INFO - PROCESS | 2312 | 22:47:32 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53187/tests/kraken/driver.html?testName=imaging-darkroom) 22:47:33 INFO - PROCESS | 2312 | RSS: Main: 326668288 22:47:33 INFO - PROCESS | 2312 | 22:47:36 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53187/tests/kraken/driver.html?testName=imaging-desaturate) 22:47:36 INFO - PROCESS | 2312 | RSS: Main: 328589312 22:47:36 INFO - PROCESS | 2312 | 22:47:39 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53187/tests/kraken/driver.html?testName=json-parse-financial) 22:47:40 INFO - PROCESS | 2312 | RSS: Main: 326606848 22:47:40 INFO - PROCESS | 2312 | 22:47:41 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53187/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:47:42 INFO - PROCESS | 2312 | RSS: Main: 301633536 22:47:42 INFO - PROCESS | 2312 | 22:47:44 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:47:44 INFO - PROCESS | 2312 | RSS: Main: 305455104 22:47:44 INFO - PROCESS | 2312 | 22:47:47 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:47:47 INFO - PROCESS | 2312 | RSS: Main: 312336384 22:47:47 INFO - PROCESS | 2312 | 22:47:50 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:47:51 INFO - PROCESS | 2312 | RSS: Main: 319823872 22:47:51 INFO - PROCESS | 2312 | 22:47:54 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:47:55 INFO - PROCESS | 2312 | RSS: Main: 319197184 22:47:55 INFO - PROCESS | 2312 | 22:47:56 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:53187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53187/tests/kraken/driver.html?testName=ai-astar) 22:47:57 INFO - PROCESS | 2312 | RSS: Main: 363417600 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | __start_tp_report 22:47:57 INFO - PROCESS | 2312 | _x_x_mozilla_page_load 22:47:57 INFO - PROCESS | 2312 | _x_x_mozilla_page_load_details 22:47:57 INFO - PROCESS | 2312 | |i|pagename|runs| 22:47:57 INFO - PROCESS | 2312 | |0;ai-astar;112;114;113;112;112;112;113;113;112;113 22:47:57 INFO - PROCESS | 2312 | |1;audio-beat-detection;158;117;115;151;116;113;116;114;143;100 22:47:57 INFO - PROCESS | 2312 | |2;audio-dft;188;181;186;183;186;187;185;219;180;185 22:47:57 INFO - PROCESS | 2312 | |3;audio-fft;79;58;58;91;61;59;57;57;80;57 22:47:57 INFO - PROCESS | 2312 | |4;audio-oscillator;82;69;91;70;68;70;93;68;69;91 22:47:57 INFO - PROCESS | 2312 | |5;imaging-gaussian-blur;86;83;86;83;86;83;91;131;120;84 22:47:57 INFO - PROCESS | 2312 | |6;imaging-darkroom;93;91;92;93;92;93;92;93;93;92 22:47:57 INFO - PROCESS | 2312 | |7;imaging-desaturate;85;86;86;86;84;84;86;83;87;84 22:47:57 INFO - PROCESS | 2312 | |8;json-parse-financial;55;51;53;51;53;53;52;52;53;54 22:47:57 INFO - PROCESS | 2312 | |9;json-stringify-tinderbox;63;75;65;76;72;68;70;70;70;71 22:47:57 INFO - PROCESS | 2312 | |10;stanford-crypto-aes;86;85;89;86;86;88;90;87;94;108 22:47:57 INFO - PROCESS | 2312 | |11;stanford-crypto-ccm;140;171;160;162;153;173;147;129;240;162 22:47:57 INFO - PROCESS | 2312 | |12;stanford-crypto-pbkdf2;169;173;170;170;172;170;170;169;175;231 22:47:57 INFO - PROCESS | 2312 | |13;stanford-crypto-sha256-iterative;68;68;68;68;68;69;68;69;68;68 22:47:57 INFO - PROCESS | 2312 | __end_tp_report 22:47:57 INFO - PROCESS | 2312 | __start_cc_report 22:47:57 INFO - PROCESS | 2312 | _x_x_mozilla_cycle_collect,1930 22:47:57 INFO - PROCESS | 2312 | __end_cc_report 22:47:57 INFO - PROCESS | 2312 | __startTimestamp1476164877184__endTimestamp 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | ------- Summary: start ------- 22:47:57 INFO - PROCESS | 2312 | Number of tests: 14 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 22:47:57 INFO - PROCESS | 2312 | Values: 112.0 114.0 113.0 112.0 112.0 112.0 113.0 113.0 112.0 113.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:116.50 stddev:19.15 (16.4%) stddev-sans-first:15.96 22:47:57 INFO - PROCESS | 2312 | Values: 158.0 117.0 115.0 151.0 116.0 113.0 116.0 114.0 143.0 100.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#2] audio-dft Cycles:10 Average:188.00 Median:186.00 stddev:11.19 (6.0%) stddev-sans-first:11.86 22:47:57 INFO - PROCESS | 2312 | Values: 188.0 181.0 186.0 183.0 186.0 187.0 185.0 219.0 180.0 185.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#3] audio-fft Cycles:10 Average:65.70 Median:60.00 stddev:12.62 (21.0%) stddev-sans-first:12.44 22:47:57 INFO - PROCESS | 2312 | Values: 79.0 58.0 58.0 91.0 61.0 59.0 57.0 57.0 80.0 57.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:76.00 stddev:10.86 (14.3%) stddev-sans-first:11.37 22:47:57 INFO - PROCESS | 2312 | Values: 82.0 69.0 91.0 70.0 68.0 70.0 93.0 68.0 69.0 91.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:85.00 stddev:17.33 (20.4%) stddev-sans-first:18.18 22:47:57 INFO - PROCESS | 2312 | Values: 86.0 83.0 86.0 83.0 86.0 83.0 91.0 131.0 120.0 84.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 22:47:57 INFO - PROCESS | 2312 | Values: 93.0 91.0 92.0 93.0 92.0 93.0 92.0 93.0 93.0 92.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.29 (1.5%) stddev-sans-first:1.36 22:47:57 INFO - PROCESS | 2312 | Values: 85.0 86.0 86.0 86.0 84.0 84.0 86.0 83.0 87.0 84.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.25 (2.4%) stddev-sans-first:1.01 22:47:57 INFO - PROCESS | 2312 | Values: 55.0 51.0 53.0 51.0 53.0 53.0 52.0 52.0 53.0 54.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.50 stddev:4.00 (5.7%) stddev-sans-first:3.35 22:47:57 INFO - PROCESS | 2312 | Values: 63.0 75.0 65.0 76.0 72.0 68.0 70.0 70.0 70.0 71.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.50 stddev:6.89 (7.9%) stddev-sans-first:7.16 22:47:57 INFO - PROCESS | 2312 | Values: 86.0 85.0 89.0 86.0 86.0 88.0 90.0 87.0 94.0 108.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#11] stanford-crypto-ccm Cycles:10 Average:163.70 Median:162.00 stddev:30.07 (18.6%) stddev-sans-first:30.65 22:47:57 INFO - PROCESS | 2312 | Values: 140.0 171.0 160.0 162.0 153.0 173.0 147.0 129.0 240.0 162.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:171.00 stddev:19.10 (11.2%) stddev-sans-first:20.05 22:47:57 INFO - PROCESS | 2312 | Values: 169.0 173.0 170.0 170.0 172.0 170.0 170.0 169.0 175.0 231.0 22:47:57 INFO - PROCESS | 2312 | 22:47:57 INFO - PROCESS | 2312 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 22:47:57 INFO - PROCESS | 2312 | Values: 68.0 68.0 68.0 68.0 68.0 69.0 68.0 69.0 68.0 68.0 22:47:57 INFO - PROCESS | 2312 | -------- Summary: end -------- 22:47:57 INFO - PROCESS | 2312 | 22:47:58 INFO - TEST-INFO | 2312: exit 0 22:47:58 INFO - TEST-OK | kraken | took 59622ms 22:47:58 INFO - SUITE-END | took 1178s 22:47:58 INFO - Completed test suite (00:19:38) 22:47:58 INFO - javascript benchmark 22:47:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2956.71911168439, 3264.4287249731306, 3275.2794385235247, 3301.6368199498065, 3345.5650765400496, 5524.972375138129, 5618.15727640854, 5655.066913959197, 5713.628614140083, 5729.28383952006, 5615.831525054255, 5648.068068768648, 5669.688167150805, 5670.716464176795, 5677.630953987988, 5115.18157094864, 5156.948430515685, 5161.329027226474, 5167.302414174094, 5191.247269145972, 5252.107141785906, 5261.1319132343115, 5261.39493958194, 5267.683938963655, 5269.946010797837, 5131.230315452674, 5174.353205849269, 5208.140656791644, 5218.947810521903, 5260.342457192851, 5245.947540524584, 5246.606504512139, 5249.711265880375, 5268.420473747885, 5349.946500535023, 5054.570361519275, 5078.060558796635, 5135.897282054375, 5142.382914050338, 5176.094183517869, 5592.664440133605, 5640.971795140998, 5652.5195358394585, 5693.544516438675, 5718.857028574277, 6212.968935155341, 6213.409726076061, 6215.720292586844, 6221.346758590328, 6225.408586184305, 6131.448169664752, 6156.830202261556, 6188.938110618881, 6266.404691554295, 6283.811485655392, 5714.685692286923, 5746.482816546531, 5752.654840709571, 5775.913361299584, 5821.243238378984, 6218.7166703871635, 6268.373162683786, 6276.748930042839, 6295.276043254993, 6301.4328710416285, 5947.137665038632, 6096.055111457731, 6106.5659535032355, 6121.418465245794, 6141.60477413809, 6102.50984716401, 6107.606420791524, 6141.004173643583, 6215.689215539205, 6247.281562620356, 5144.742762861842, 6172.6913654318005, 6173.783917562953, 6174.9073763893575, 6191.597546159485, 6194.628322300677, 6206.875862482725, 6210.099535567318, 6215.471684906744, 6232.065190221503, 4704.270838020044, 4853.854384368475, 4857.149998750205, 4858.489858564871, 4879.414470263591, 4365.432493775789, 4365.738055716667, 4404.603585677304, 4432.3794668746405, 4448.132614140211, 5673.744681489343, 5711.400302968129, 5747.252857128547, 5767.0, 5794.710264486759, 6179.721912513947, 6240.375962403723, 6247.9062814057825, 6284.748610055546, 6286.9056964145575, 5609.0184217761725, 5660.547156227574, 5675.404082571353, 5717.0, 5735.369109397963, 5356.758945847446, 5372.946270537323, 5374.435684253175, 5377.220303056035, 5379.811706590251, 6217.285012223561, 6259.561830671809, 6266.185395898595, 6272.278687950761, 6275.058741188767, 6182.598131121463, 6228.937710622836, 6244.500439964882, 6272.560920735505, 6277.027060805583, 6259.937400625936, 6276.089966954857, 6297.622142671455, 6301.0, 6302.779402720883, 6038.245219347582, 6086.786962456293, 6105.672036201006, 6109.3890610940325, 6135.785247516494, 6186.381361863958, 6195.349488303844, 6219.595726277777, 6248.9062664059165, 6261.530385221035, 5112.207607820794, 6071.574989750675, 6104.969475152773, 6110.419510146707, 6127.785527506516, 5909.23179986599, 5957.970210148921, 6076.3012253591405, 6087.4521293083835, 6098.115773212948, 5841.970790146022, 5844.26946631671, 5892.469677729025, 5909.113632955091, 5956.8808623828145, 6057.666828324484, 6072.332043475126, 6078.817635470943, 6087.421694938974, 6094.78668246627, 5757.769689212385, 5854.385289544536, 5882.470577648032, 5905.055191169392, 5966.8209953699725], "value": 5660.571956024889, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.55249289421465, 510.9856933986039, 512.369785164248, 517.5342192027175, 535.1170568561872, 28973.420531589356, 30123.54814677779, 30800.38399232014, 31244.99999999997, 31345.216369590773, 31529.369412611733, 32195.517067243953, 32221.677783222218, 33240.67315287931, 42004.739857804314, 9495.335326527129, 9672.226221902267, 9720.173785228264, 9725.319227654067, 9743.0, 9516.048395160498, 9626.855597166048, 9630.3258771886, 9632.373895696757, 9652.758681032996, 9619.855702164474, 9659.275554333419, 9661.855072173923, 9668.129868311851, 9673.0, 9572.28207884408, 9613.182879455255, 9616.855747163798, 9645.565949532252, 9649.179819715333, 9532.95233523835, 9596.32825702201, 9606.855897161548, 9614.51927403634, 9639.614415423375, 39162.21675566501, 39325.60674393277, 39427.40858887119, 39479.210415791524, 39484.0, 3235.1912021994503, 3286.0, 3324.4182268102986, 3402.4688889999597, 3436.2096717755057, 30363.696363036306, 31230.0, 31271.0, 31375.05874823759, 31690.207744806336, 3148.102790704638, 3160.7945483543494, 3320.7841490303053, 3387.7797943133864, 3399.2351720862857, 443575.0, 448870.0, 449819.0, 450754.0, 461822.0, 55789.72105139448, 56220.0, 56266.21737152388, 56284.0, 56295.87408251812, 57541.424585754445, 57717.0, 57742.0, 57762.71118644464, 57885.71057144687, 15553.22233888301, 15768.842311576967, 15784.92107539455, 15792.842071579367, 15799.131047792482, 12396.132270740962, 12437.378131093408, 12452.252864828139, 12455.750884982432, 12466.252024878537, 14051.648708782157, 14570.708585828224, 14677.55967320982, 14684.559463216121, 14828.481003164838, 6009.639421634716, 6104.511639068953, 6208.4722798561725, 6248.937510624926, 6278.466330361879, 727.3490226247479, 730.8778489320238, 759.2597217712686, 798.62863768348, 885.3050355470924, 23246.65130023051, 26728.064517741786, 29295.707042929724, 29649.703502965127, 29895.953641622873, 929.5398777604994, 1045.5608644369383, 1059.6873922192817, 1114.8216285394299, 1210.1831263896834, 14613.634659133606, 15104.242163718394, 15171.468998584996, 15311.194674021976, 15339.156346401054, 608864.0, 611970.0, 612334.2599444047, 612636.0, 614377.0, 56833.43166568364, 57056.0, 57122.0, 57278.427215728145, 57323.0, 1345.7577636025414, 1385.8129152564275, 1397.657573894382, 1400.208881981677, 1402.2988505747126, 28457.0, 28681.996130135347, 28981.0, 29403.264918377212, 29545.704542954296, 47266.0, 47390.81522961953, 47678.0, 47722.0, 47733.52266477291, 551412.0, 554040.2297988484, 554709.0, 555135.0, 555729.0, 37809.0, 38400.847974560806, 38552.0, 38841.6115838838, 38943.80528097455, 35642.18830457855, 45441.772791137155, 45602.0, 46109.07781844277, 47047.294290584985, 38282.0, 38526.229475409775, 39049.58057968977, 39116.02209944774, 39573.60426395814, 38029.61970380261, 38485.0, 38675.61324386833, 39478.01304967399, 39610.2077958445, 31852.885149019672, 32410.83794581012, 32749.0, 36770.632293677794, 36952.445713313784, 27355.13642262882, 28972.27569310784, 32668.0, 33062.33875322528, 34067.65932340744, 19735.21059157675, 20873.582528349652, 25154.62268065944, 25217.49565008653, 25657.845396957557], "value": 19084.12536666119, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.7810922900115, 362.7678285897026, 368.8395547936647, 386.46088706254756, 394.5657534860782, 19434.0, 20067.197312107517, 21276.0, 21531.892340538274, 21564.35306940794, 20424.183032678677, 20655.27706530268, 21041.26355577555, 21397.358079257647, 21425.250116245894, 4542.977285113569, 5381.488758567949, 5868.119782032687, 6104.328523862371, 6105.908411373833, 6119.571629985903, 6160.7535698572, 6172.0, 6182.0, 6185.381461853801, 6065.211522502069, 6171.660558669271, 6727.024581435713, 6802.38778509934, 6829.931700683004, 5777.422257774189, 6753.594784312957, 6801.863962720718, 6810.3189681031745, 6821.386075253251, 5804.332501762267, 5947.970260148715, 5951.196588460546, 5953.3748956359395, 5955.34491205967, 10864.0, 13259.801102983463, 13339.666508337272, 13378.598642040657, 13583.185008899498, 2046.1815273890563, 2129.6379615465407, 2319.7100362454694, 2337.7077865266842, 2367.4791545859885, 9795.265355098325, 9807.607695692164, 9840.507974601242, 10306.79386412275, 10502.317349372266, 1810.6469238498498, 1826.3881599663996, 1860.9720854187062, 1868.277033402533, 1886.877352972066, 108475.0, 115478.0, 117303.41348293204, 117540.41229793796, 119066.40466797697, 9953.751156221007, 10511.10655594283, 11189.944050279697, 11295.661130166109, 11754.35351055683, 14136.15183089018, 14139.787903181461, 14183.929080354532, 14184.716305674036, 14217.64455888611, 8081.797955051171, 8229.46508476947, 8242.464239824512, 8250.0, 8415.495070295801, 8138.470999385021, 8148.796280093045, 8301.169883011771, 8332.416730828783, 8364.832703345899, 3564.8752293669595, 4413.823447062082, 4418.911621767546, 4495.57292057254, 4501.7974191161475, 1685.1405783050732, 1813.7551430556705, 1901.8858868467937, 1917.1756144857845, 1936.2642195965443, 801.8235988082613, 866.9826603468022, 904.6749852878172, 918.3020904112789, 987.1757082835769, 15524.611884702746, 20167.0, 20330.390088297376, 20537.25433338153, 21179.258725944826, 855.3328403845011, 936.0358830404695, 937.6098374776618, 975.3806332978468, 980.7302991677345, 10897.91020897904, 11220.0, 11378.374189419495, 11406.714832129263, 11419.942900285612, 576454.0, 579677.0, 582514.0, 591979.0, 592532.0, 136252.31873840568, 141456.0, 143376.0, 144311.0, 145610.0, 1015.8527013582989, 1018.5060245780791, 1042.5777560088025, 1042.61944390297, 1044.300318786415, 18702.438926832216, 18838.52903677419, 19091.890595009463, 19117.90441047786, 19141.330053448062, 54115.72942135264, 55207.0, 55690.0, 56001.43998559962, 56990.0, 83286.58356708178, 84870.0, 85195.5740221295, 85486.71769923331, 86784.18942295224, 7856.84286314282, 8021.438499304993, 8120.14738452454, 8138.633761480866, 8183.304419124441, 11083.0, 13006.739865202453, 13007.219566826021, 13260.336983150997, 13296.534621288209, 11218.0, 11257.268277561932, 11325.15061370417, 11398.08815294791, 11401.20191586581, 8184.8772268417215, 10896.673099807018, 11091.389973551532, 11408.315501069963, 11529.423528823358, 11064.170187235828, 11227.887721122685, 11310.982011618993, 11394.25937314072, 11737.35444550558, 6222.844428889314, 8126.959365203136, 8130.796730081795, 8204.917950820416, 8402.957985210034, 10753.89246107529, 11273.830892536454, 11331.250248775877, 11497.482613282255, 11534.711632209262], "value": 9449.466852470927, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.7136815584647, 1505.932233049513, 1506.1716056169105, 1730.2646375290499, 1926.0947354743266, 7494.437917156215, 7934.2065793420625, 7942.92057079429, 7959.965204523411, 8025.478343907642, 8102.837943241132, 8314.542700151489, 8315.501069935808, 8336.333093352532, 8339.54132522711, 2259.830512711549, 2299.5515874404496, 2303.608386574278, 2304.1704986205036, 2307.7346105197867, 2248.7076680031573, 2279.31620513846, 2300.574393737156, 2301.390131615123, 2308.6190778521523, 2207.073029327686, 2229.9331020069426, 2242.125571027301, 2247.674087257347, 2260.0959616153505, 2241.5628952354296, 2272.8067764423727, 2274.283600665785, 2275.6472746724203, 2283.212291759357, 2262.9547409051893, 2264.52444986553, 2264.52444986553, 2267.977320226793, 2281.555096756133, 8310.335173186131, 8334.874976875351, 8335.374846886507, 8338.124496927796, 8374.706885259047, 4896.485868983741, 4934.432540257844, 5112.437631860493, 5162.690238585659, 5177.741112944375, 3326.151831283007, 3490.7207423406066, 3566.964330356689, 3567.1438854674952, 3571.8571257149683, 1046.9476526173737, 1063.3301020357203, 1064.0689396777818, 1084.2572837606185, 1084.5878566144893, 10615.734606634818, 10730.892691073068, 10843.891561084367, 10854.077403420719, 10872.967068128515, 4391.363252328394, 4401.185780630593, 4585.28928016158, 4592.977035114803, 4597.885052873705, 9571.042895710374, 9598.184154346958, 9602.279829012852, 9608.327417080738, 9639.421634701801, 5154.278401023859, 5158.123119069767, 5162.81255311273, 5169.06956747789, 5169.534741873174, 3880.534335879656, 4218.535961044283, 4278.486581610165, 4380.386745855582, 4654.720716757005, 3579.51676523666, 3581.1047238190454, 3778.8852288574935, 3893.4354518594646, 3930.4104384342577, 1047.5652604169336, 1221.712897469094, 1234.4074844074714, 1256.8177614246065, 1257.7421628566121, 794.2811755361291, 864.1401805203736, 908.6274627402865, 917.5962576466334, 948.83869742144, 5423.213634023124, 5540.335159780771, 5741.253637027244, 5800.709964501758, 5993.190919225936, 950.8906475755239, 957.712686194139, 973.9610415583297, 974.551706215149, 1014.2291858187683, 2843.516602177635, 3775.6413140751583, 3868.7872167031082, 3977.1449138434796, 3997.340438827619, 9787.804243915081, 10249.0, 10256.743581410525, 10272.0, 10290.691279261635, 8594.269487093543, 10002.0, 10214.285000050073, 10228.53971571281, 10239.0, 7653.808654783564, 7867.370610351159, 7876.803079922933, 7876.842463150705, 7880.330171935335, 5576.414476479911, 5630.605857590093, 5729.427057294237, 5857.5313974881055, 5992.250968628921, 8145.674173033012, 9371.156595906401, 9404.623815047322, 9470.905290947003, 9561.760955976157, 7977.880331794911, 8689.522076285864, 9076.31927605419, 9462.2430205582, 9489.335746497678, 6700.095487109178, 6872.518923675295, 6900.137482814648, 6918.688659010458, 6919.273476285119, 7692.499987500794, 7912.683492660229, 7925.564093974886, 8034.196580342153, 8086.595670216583, 7165.964170179324, 7229.7469588566255, 7294.1247050354295, 7332.41340692754, 7374.299441553044, 7104.431645468453, 7117.1459424869345, 7145.535540189871, 7205.351518363373, 7261.745838895613, 6633.170853643294, 6637.137172167781, 6643.3356664335115, 6731.596104233761, 6741.426978706865, 4793.400824896888, 4894.804207831789, 4900.187817284126, 4941.481144479778, 5022.799088036438, 4794.184988551885, 4916.803327866988, 4968.329275547755, 4981.825636102719, 5065.670731402447], "value": 4399.110982257477, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.27932960893855, 301.663645035785, 310.16410772966856, 315.7773769492508, 317.83472594250975, 5141.6914985100875, 5153.56194723449, 5170.682480129092, 5181.4300426953005, 5183.766730497127, 4771.90456190876, 4959.008198360324, 4976.950230497694, 4985.750712464372, 5103.41310749263, 3756.0, 3882.631594727207, 3921.607839216084, 3989.4813674222455, 4001.9599804001878, 3928.17508323251, 3952.3873799561115, 3974.1455587048777, 4003.3194356959243, 4036.8587099451524, 3522.4892390603477, 3652.3476523476525, 3825.445310429986, 3962.9207415851665, 3980.2039592081555, 3903.3559462688904, 3930.78380689062, 3975.8807235782974, 3986.900327491807, 3988.4416181734728, 3465.4455287153933, 3467.7572569920117, 3469.9132521686906, 3488.354654388946, 3498.265364273506, 5022.773975171126, 5023.095842748304, 5031.396232452093, 5033.471485494044, 5039.916417970117, 4046.3121269383983, 4394.1651086293505, 4398.032432864765, 4416.116776644684, 4430.6676999224865, 5299.49654782795, 5309.106536937378, 5330.387005494379, 5354.14333706609, 5376.198973343934, 3232.9676703232903, 3678.062094165997, 3682.723795715332, 3687.94468082979, 3688.963110368889, 4288.499025341137, 5470.17947307903, 5477.794885125266, 5570.248016517799, 5594.4964953154, 3271.8691252350113, 3691.852325906934, 3698.0, 3707.629237076271, 3722.4974628424816, 3582.964170358263, 3732.011017080476, 3743.989122936792, 3744.884140460631, 3755.5681096673684, 3673.504076949631, 3903.8048097595006, 4152.283556996923, 4402.7358358498595, 4410.80151393188, 4766.523347665206, 5351.919721204107, 5359.866003349869, 5400.081986062379, 5403.297961142228, 1036.79782442423, 1261.4764872577962, 1275.2476039136955, 1285.9871401285868, 1286.1832736212573, 813.5850716134696, 829.4940086547201, 838.3335248477475, 853.2534032721369, 876.8684697295329, 4628.490866004737, 4669.976650116728, 5028.396592408934, 5063.367079115111, 5084.0, 4501.257292546758, 4850.563449289581, 5062.417821950449, 5503.422140675251, 5615.887682246333, 4117.826419470496, 4691.83578574755, 5050.419201791841, 5110.901156251423, 5111.233315002779, 2789.5257806172594, 2894.580285858539, 2930.326025014216, 2934.8385838779072, 2949.483840327934, 5713.914291285634, 5738.799142030009, 5770.278715160605, 5778.8266352009505, 5798.10129429939, 5066.873328166752, 5137.743112844343, 5667.271482197969, 5728.250439788924, 5744.253247077937, 4737.881552961135, 4744.715317080986, 5352.74210560518, 5477.452254774491, 5828.912566311424, 5255.63210575256, 5276.604254680838, 5554.333479982428, 5607.0, 5677.971610142088, 5451.972740136274, 5479.726013699219, 5486.299247233371, 5522.116461366162, 5524.889502210013, 4536.750478723702, 5710.972025035533, 5746.856328591819, 5821.592488525763, 5854.331515518057, 3127.9374412511165, 3585.9103522412147, 3626.8549258029384, 3692.630736926286, 3749.6812770914776, 4801.303810947463, 4916.901661966669, 5473.562115030868, 5519.889602207853, 5543.503254121284, 5369.19462080691, 5378.623496355218, 5461.590380721541, 5489.231507589021, 5499.3675727291875, 4723.8114272865, 4870.391201099863, 5213.817516386908, 5363.0, 5404.811051524139, 4793.736344500946, 4886.0, 4999.450060493416, 5071.163258062378, 5294.729264976301, 5287.656302340336, 5345.65253258537, 5368.570514358764, 5435.891282174255, 5450.400455949922, 4648.093621743727, 5044.873878153076, 5333.7866485340155, 5351.518363347317, 5358.544523715528], "value": 3947.9774124750124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.5825542057896, 721.592300350302, 771.0593076446733, 797.3660939553055, 816.4448175240835, 15486.767698484531, 15659.0, 15765.0, 15783.21083945804, 15821.446249381273, 14111.364988575513, 14217.92891035546, 14230.71538569228, 14253.429862805475, 14261.286935653201, 673.5318953327439, 699.0143897105083, 700.1318365227132, 850.5492089192707, 877.806882485852, 851.531657588327, 873.2315562305163, 878.1131057631806, 878.7275944457239, 880.9779755506099, 933.4166146158651, 934.8364036293622, 936.2416442681416, 937.4562753602894, 937.4843835890233, 725.4522835259398, 748.4685873030155, 903.602414937432, 929.6978481993345, 931.7950050988771, 698.559907258429, 698.9231184569693, 700.3276854219955, 703.5673061067407, 703.9753608623673, 15555.22223888801, 15597.986130901569, 15600.141992190425, 15616.531504054896, 15628.765568516481, 14133.929330353283, 14302.14187148764, 14320.283985800668, 14331.498397556035, 14447.916406269467, 10642.893571064267, 12723.809142862865, 12839.935800321033, 12880.42038108278, 13412.0, 4597.37935378723, 4603.930941035887, 4729.337892694991, 4802.289463581459, 4847.006363695434, 115529.0, 117085.36432945881, 118908.0, 122579.0, 122680.77319226782, 11058.889411105947, 11254.720228266924, 11342.092632589372, 11483.023942964774, 11566.958973692408, 22592.548149037255, 22596.322110336718, 22606.0, 22610.869456527107, 22633.886830566073, 6458.903116453257, 7740.303372696371, 7830.373570114377, 7978.5611791350875, 8039.758807235792, 4126.360414135814, 4295.871123866289, 4298.4197133387215, 4329.891752706145, 4374.147041326974, 3327.450970589874, 3477.4783782432332, 3484.790912545255, 3487.1805125795418, 3489.5114683944266, 626.3830127324673, 626.8045307603107, 645.8062581225613, 646.3762469217228, 647.0811447744214, 555.8526990347561, 600.8738164985315, 632.509804901196, 643.4948565376158, 644.5971267957526, 8701.825963480695, 8992.190702836777, 9170.541472926327, 9232.584533696, 9308.348415610977, 552.6838966202783, 570.120928764471, 621.18314416543, 631.4632562322081, 645.6513482719279, 4104.281750693616, 4392.538783427758, 4480.215962206601, 4529.230030894756, 4607.746573938399, 98189.0, 134369.0, 155598.0, 163929.0, 166440.0, 120976.0, 142658.28670856787, 143418.0, 145507.272463637, 146336.0, 96118.0, 116683.41658291825, 118536.0, 119837.0, 121132.0, 8519.105493923049, 8520.74437766868, 8580.227779499875, 8755.649774008969, 8828.602712878064, 66677.0, 66692.666536667, 67256.0, 68447.94145288029, 69279.3072069293, 95172.0, 99829.0, 102766.0, 103030.45454318041, 105380.0, 11724.472398741906, 12059.457324420247, 12071.939640301742, 12088.576899808464, 12308.630741077783, 11996.100292477924, 12012.05389385621, 12308.876911231131, 12442.191257568229, 12809.487620495449, 11614.941925290319, 11995.340256285988, 12026.27842329463, 12314.445849936954, 12352.691182720504, 11809.23239989398, 11821.349825759666, 11877.643670689547, 11958.521659133537, 11990.28058316469, 11720.296782193096, 11730.150081727876, 11951.521939122338, 11976.58081967127, 12000.219985700902, 9307.627694892128, 9854.568054692356, 9877.40735555869, 10076.798464030531, 10263.640772572924, 9802.460864652512, 10032.398056116657, 10057.346272492265, 10082.445465499175, 10129.493525323851], "value": 7728.313649198845, "unit": "score"}], "name": "dromaeo_css", "value": 7180.17902669445, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 117.0, 115.0, 151.0, 116.0, 113.0, 116.0, 114.0, 143.0, 100.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 186.0, 183.0, 186.0, 187.0, 185.0, 219.0, 180.0, 185.0], "value": 188.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 91.0, 61.0, 59.0, 57.0, 57.0, 80.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 91.0, 70.0, 68.0, 70.0, 93.0, 68.0, 69.0, 91.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 86.0, 83.0, 91.0, 131.0, 120.0, 84.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 86.0, 84.0, 84.0, 86.0, 83.0, 87.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 53.0, 51.0, 53.0, 53.0, 52.0, 52.0, 53.0, 54.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 65.0, 76.0, 72.0, 68.0, 70.0, 70.0, 70.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 89.0, 86.0, 86.0, 88.0, 90.0, 87.0, 94.0, 108.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 171.0, 160.0, 162.0, 153.0, 173.0, 147.0, 129.0, 240.0, 162.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 170.0, 170.0, 172.0, 170.0, 170.0, 169.0, 175.0, 231.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1459.9, "alertThreshold": 2.0}]} 22:47:58 INFO - Return code: 0 22:47:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:47:58 INFO - # TBPL SUCCESS # 22:47:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:47:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:47:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:47:58 INFO - 2016-10-10 22:47:58.514 screenresolution[2316:292348] starting screenresolution argv=screenresolution get 22:47:58 INFO - 2016-10-10 22:47:58.516 screenresolution[2316:292348] Display 0: 1600x1200x32@60 22:47:58 INFO - 2016-10-10 22:47:58.522 screenresolution[2317:292351] starting screenresolution argv=screenresolution list 22:47:58 INFO - Available Modes on Display 0 22:47:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:47:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:47:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:47:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:47:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:47:58 INFO - Intel Iris: 22:47:58 INFO - Chipset Model: Intel Iris 22:47:58 INFO - Type: GPU 22:47:58 INFO - Bus: Built-In 22:47:58 INFO - VRAM (Dynamic, Max): 1536 MB 22:47:58 INFO - Vendor: Intel (0x8086) 22:47:58 INFO - Device ID: 0x0a2e 22:47:58 INFO - Revision ID: 0x0009 22:47:58 INFO - Displays: 22:47:58 INFO - Display: 22:47:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:47:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:47:58 INFO - Display Serial Number: 1600x1200 60 22:47:58 INFO - Main Display: Yes 22:47:58 INFO - Mirror: Off 22:47:58 INFO - Online: Yes 22:47:58 INFO - Rotation: Supported 22:47:58 INFO - Return code: 0 22:47:58 INFO - Running post-action listener: _resource_record_post_action 22:47:58 INFO - [mozharness: 2016-10-11 05:47:58.761705Z] Finished run-tests step (success) 22:47:58 INFO - Running post-run listener: _resource_record_post_run 22:47:58 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 569344; Write bytes: 705649152; Read time: 5; Write time: 1924 22:47:58 INFO - TinderboxPrint: CPU usage
53.4% 22:47:58 INFO - TinderboxPrint: I/O read bytes / time
569,344 / 5 22:47:58 INFO - TinderboxPrint: I/O write bytes / time
705,649,152 / 1,924 22:47:58 INFO - TinderboxPrint: CPU idle
2,258.5 (46.6%) 22:47:58 INFO - TinderboxPrint: CPU system
965.0 (19.9%) 22:47:58 INFO - TinderboxPrint: CPU user
1,626.2 (33.5%) 22:47:58 INFO - TinderboxPrint: Swap in / out
407,855,104 / 0 22:47:58 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 408504832; Write bytes: 411565568; Read time: 30334; Write time: 767 22:47:58 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 532480; Write bytes: 292658176; Read time: 4; Write time: 1149 22:47:59 INFO - Running post-run listener: _upload_blobber_files 22:47:59 INFO - Blob upload gear active. 22:47:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:47:59 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 22:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:47:59 INFO - (blobuploader) - INFO - Open directory for files ... 22:47:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:47:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:48:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:00 INFO - (blobuploader) - INFO - Done attempting. 22:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:01 INFO - (blobuploader) - INFO - Done attempting. 22:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:04 INFO - (blobuploader) - INFO - Done attempting. 22:48:04 INFO - (blobuploader) - INFO - Iteration through files over. 22:48:04 INFO - Return code: 0 22:48:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:48:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:48:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bb2d665319e06c65eea99f458da47e4c85db019f72a41e956eaac11d4bc4979383811cd512a1130aa46282595d62c1143794dcf9ce72c2b51e79ff8a106627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fe80eed0b11284bb11fefa6527b6879bfe56d06f404820421dc46d22719b8f554b5b5e413d8cfec3a2313b4f705e2f52f909e0d482a7b4856b94c36261c1841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7827cdea191c7896a724115135ba1e9fd4610911e4c9d0ed2bdcfd34e6cc712ec6aa7382cb6367119cffc9332a8762a443d48fcb469443f45ecaef89d2757d8c"} 22:48:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:48:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:48:04 INFO - Contents: 22:48:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bb2d665319e06c65eea99f458da47e4c85db019f72a41e956eaac11d4bc4979383811cd512a1130aa46282595d62c1143794dcf9ce72c2b51e79ff8a106627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fe80eed0b11284bb11fefa6527b6879bfe56d06f404820421dc46d22719b8f554b5b5e413d8cfec3a2313b4f705e2f52f909e0d482a7b4856b94c36261c1841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7827cdea191c7896a724115135ba1e9fd4610911e4c9d0ed2bdcfd34e6cc712ec6aa7382cb6367119cffc9332a8762a443d48fcb469443f45ecaef89d2757d8c"} 22:48:04 INFO - Running post-run listener: copy_logs_to_upload_dir 22:48:04 INFO - Copying logs to upload dir... 22:48:04 INFO - mkdir: /builds/slave/test/build/upload/logs 22:48:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.627429 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-10 22:48:04.295272) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 22:48:04.299338) ========= 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.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bb2d665319e06c65eea99f458da47e4c85db019f72a41e956eaac11d4bc4979383811cd512a1130aa46282595d62c1143794dcf9ce72c2b51e79ff8a106627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fe80eed0b11284bb11fefa6527b6879bfe56d06f404820421dc46d22719b8f554b5b5e413d8cfec3a2313b4f705e2f52f909e0d482a7b4856b94c36261c1841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7827cdea191c7896a724115135ba1e9fd4610911e4c9d0ed2bdcfd34e6cc712ec6aa7382cb6367119cffc9332a8762a443d48fcb469443f45ecaef89d2757d8c"} build_url:https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011638 build_url: 'https://queue.taskcluster.net/v1/task/Xf44UzDkTVmD5i5EGOM81Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bb2d665319e06c65eea99f458da47e4c85db019f72a41e956eaac11d4bc4979383811cd512a1130aa46282595d62c1143794dcf9ce72c2b51e79ff8a106627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fe80eed0b11284bb11fefa6527b6879bfe56d06f404820421dc46d22719b8f554b5b5e413d8cfec3a2313b4f705e2f52f909e0d482a7b4856b94c36261c1841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7827cdea191c7896a724115135ba1e9fd4610911e4c9d0ed2bdcfd34e6cc712ec6aa7382cb6367119cffc9332a8762a443d48fcb469443f45ecaef89d2757d8c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 22:48:04.332645) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:48:04.332995) ========= 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.yjjg5ld2XQ/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.occbHFdyhI/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005457 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 22:48:04.381907) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 22:48:04.382246) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 22:48:04.382590) ========= ========= Total master_lag: 0.18 =========