builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1487619329.11 results: success (0) buildid: 20170220070057 builduid: 59d38ecc375c4f3d8a75893d12924e50 revision: 3b31bcb88fe341172e93cc8b1143e617c0988694 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.114954) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.115427) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.116475) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.187474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.187851) ========= 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.aQQDfE7hat/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.XUAhrkxQho/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 --2017-02-20 11:35:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.1M=0.001s 2017-02-20 11:35:29 (20.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.533971 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.749562) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.749889) ========= 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.aQQDfE7hat/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.XUAhrkxQho/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.027542 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:29.829545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 11:35:29.829909) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3b31bcb88fe341172e93cc8b1143e617c0988694 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3b31bcb88fe341172e93cc8b1143e617c0988694 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aQQDfE7hat/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.XUAhrkxQho/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 2017-02-20 11:35:29,940 truncating revision to first 12 chars 2017-02-20 11:35:29,940 Setting DEBUG logging. 2017-02-20 11:35:29,940 attempt 1/10 2017-02-20 11:35:29,940 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3b31bcb88fe3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 11:35:30,736 unpacking tar archive at: mozilla-beta-3b31bcb88fe3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.041714 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 11:35:30.903902) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:30.904465) ========= script_repo_revision: 3b31bcb88fe341172e93cc8b1143e617c0988694 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:30.905101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:30.905585) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 11:35:30.927110) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-20 11:35:30.927752) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aQQDfE7hat/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.XUAhrkxQho/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 11:35:31 INFO - MultiFileLogger online at 20170220 11:35:31 in /builds/slave/test 11:35:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:35:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:35:31 INFO - {'append_to_log': False, 11:35:31 INFO - 'base_work_dir': '/builds/slave/test', 11:35:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:35:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:35:31 INFO - 'branch': 'Mozilla-Beta', 11:35:31 INFO - 'buildbot_json_path': 'buildprops.json', 11:35:31 INFO - 'config_files': ('talos/mac_config.py',), 11:35:31 INFO - 'default_actions': ('clobber', 11:35:31 INFO - 'read-buildbot-config', 11:35:31 INFO - 'download-and-extract', 11:35:31 INFO - 'populate-webroot', 11:35:31 INFO - 'create-virtualenv', 11:35:31 INFO - 'install', 11:35:31 INFO - 'run-tests'), 11:35:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:35:31 INFO - 'download_minidump_stackwalk': True, 11:35:31 INFO - 'download_symbols': 'ondemand', 11:35:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:35:31 INFO - 'tooltool.py': '/tools/tooltool.py', 11:35:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:35:31 INFO - '/tools/misc-python/virtualenv.py')}, 11:35:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:35:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:35:31 INFO - 'installer_path': 'installer.exe', 11:35:31 INFO - 'log_level': 'info', 11:35:31 INFO - 'log_name': 'talos', 11:35:31 INFO - 'log_to_console': True, 11:35:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:35:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:35:31 INFO - 'opt_config_files': (), 11:35:31 INFO - 'pip_index': False, 11:35:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:35:31 INFO - 'cmd': ('bash', 11:35:31 INFO - '-c', 11:35:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:35:31 INFO - 'enabled': True, 11:35:31 INFO - 'halt_on_failure': False, 11:35:31 INFO - 'name': 'check_screen_resolution'},), 11:35:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:35:31 INFO - 'cmd': ('bash', 11:35:31 INFO - '-c', 11:35:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:35:31 INFO - 'enabled': True, 11:35:31 INFO - 'halt_on_failure': False, 11:35:31 INFO - 'name': 'check_screen_resolution'},), 11:35:31 INFO - 'run_cmd_checks_enabled': True, 11:35:31 INFO - 'sps_profile': False, 11:35:31 INFO - 'sps_profile_interval': 0, 11:35:31 INFO - 'suite': 'dromaeojs', 11:35:31 INFO - 'system_bits': '32', 11:35:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:35:31 INFO - 'title': 't-yosemite-r7-0029', 11:35:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:35:31 INFO - 'use_talos_json': True, 11:35:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:35:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:35:31 INFO - 'work_dir': 'build'} 11:35:31 INFO - [mozharness: 2017-02-20 19:35:31.149216Z] Running clobber step. 11:35:31 INFO - Running pre-action listener: _resource_record_pre_action 11:35:31 INFO - Running main action method: clobber 11:35:31 INFO - rmtree: /builds/slave/test/build 11:35:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:35:33 INFO - Running post-action listener: _resource_record_post_action 11:35:33 INFO - [mozharness: 2017-02-20 19:35:33.678302Z] Finished clobber step (success) 11:35:33 INFO - [mozharness: 2017-02-20 19:35:33.678492Z] Running read-buildbot-config step. 11:35:33 INFO - Running pre-action listener: _resource_record_pre_action 11:35:33 INFO - Running main action method: read_buildbot_config 11:35:33 INFO - Using buildbot properties: 11:35:33 INFO - { 11:35:33 INFO - "project": "", 11:35:33 INFO - "product": "firefox", 11:35:33 INFO - "script_repo_revision": "production", 11:35:33 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 11:35:33 INFO - "repository": "", 11:35:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 11:35:33 INFO - "buildid": "20170220070057", 11:35:33 INFO - "pgo_build": "False", 11:35:33 INFO - "basedir": "/builds/slave/test", 11:35:33 INFO - "buildnumber": 6, 11:35:33 INFO - "slavename": "t-yosemite-r7-0029", 11:35:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:35:33 INFO - "platform": "yosemite_r7", 11:35:33 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:35:33 INFO - "branch": "mozilla-beta", 11:35:33 INFO - "repo_path": "releases/mozilla-beta", 11:35:33 INFO - "revision": "3b31bcb88fe341172e93cc8b1143e617c0988694", 11:35:33 INFO - "stage_platform": "macosx64", 11:35:33 INFO - "builduid": "59d38ecc375c4f3d8a75893d12924e50", 11:35:33 INFO - "slavebuilddir": "test" 11:35:33 INFO - } 11:35:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 11:35:33 INFO - Running post-action listener: _resource_record_post_action 11:35:33 INFO - [mozharness: 2017-02-20 19:35:33.681833Z] Finished read-buildbot-config step (success) 11:35:33 INFO - [mozharness: 2017-02-20 19:35:33.681938Z] Running download-and-extract step. 11:35:33 INFO - Running pre-action listener: _resource_record_pre_action 11:35:33 INFO - Running main action method: download_and_extract 11:35:33 INFO - mkdir: /builds/slave/test/build/tests 11:35:33 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')]} 11:35:33 INFO - trying https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 11:35:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 11:35:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 11:35:35 INFO - Downloaded 1165 bytes. 11:35:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 11:35:35 INFO - Using the following test package requirements: 11:35:35 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 11:35:35 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 11:35:35 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 11:35:35 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:35:35 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 11:35:35 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 11:35:35 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 11:35:35 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 11:35:35 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 11:35:35 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 11:35:35 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 11:35:35 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 11:35:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 11:35:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 11:35:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 11:35:35 INFO - Http code: 200 11:35:35 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 11:35:35 INFO - x-amz-cf-id: JMMNoldTVAgORNEPbWQhSbx4aKTuB4DR5Xb8gGhyvgE5aMvuL6e0og== 11:35:35 INFO - x-amz-version-id: WXHVCAFzgHduEx2TlO.WZ5DqcuQNSX0s 11:35:35 INFO - Expected file size: 16093036 11:35:35 INFO - Obtained file size: 16093036 11:35:42 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:35:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 11:35:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 11:35:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 11:35:43 INFO - Http code: 200 11:35:43 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 11:35:43 INFO - x-amz-cf-id: FHaCVq1cU7syalrteDJhmPTtL9H4wrtHvTcN2FrSmmXI0w2BS6Kk1A== 11:35:43 INFO - x-amz-version-id: WXHVCAFzgHduEx2TlO.WZ5DqcuQNSX0s 11:35:43 INFO - Expected file size: 16093036 11:35:43 INFO - Obtained file size: 16093036 11:35:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 11:35:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 11:35:49 INFO - Fetch https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 11:35:50 INFO - Http code: 200 11:35:50 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 11:35:50 INFO - x-amz-cf-id: qkZvdFKHUOEoqOBJo6ftDLLYpS6IbnLoCVWGWQsiFIom8yBg44IVhQ== 11:35:50 INFO - x-amz-version-id: MLEQ7EWjV8w8U1s1UAIlILfc.p0H_lpj 11:35:50 INFO - Expected file size: 14403005 11:35:50 INFO - Obtained file size: 14403005 11:35:50 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')]} 11:35:50 INFO - trying https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 11:35:50 INFO - Downloading https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 11:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 11:35:53 INFO - Downloaded 82139758 bytes. 11:35:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 11:35:53 INFO - mkdir: /builds/slave/test/properties 11:35:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:35:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:35:53 INFO - Contents: 11:35:53 INFO - build_url:https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 11:35:54 INFO - Running post-action listener: _resource_record_post_action 11:35:54 INFO - Running post-action listener: set_extra_try_arguments 11:35:54 INFO - [mozharness: 2017-02-20 19:35:54.624664Z] Finished download-and-extract step (success) 11:35:54 INFO - [mozharness: 2017-02-20 19:35:54.624809Z] Running populate-webroot step. 11:35:54 INFO - Running pre-action listener: _resource_record_pre_action 11:35:54 INFO - Running main action method: populate_webroot 11:35:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:35:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:35:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:35:54 INFO - '--tppagecycles', 11:35:54 INFO - '7'], 11:35:54 INFO - 'tests': ['tsvgm']}}, 11:35:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:35:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:35:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:35:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:35:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:35:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:35:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['damp', 'tps']}, 11:35:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'tests': ['damp', 'tps']}, 11:35:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['dromaeo_dom']}, 11:35:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:35:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['basic_compositor_video']}, 11:35:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:35:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'other-e10s': {'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['a11yr', 11:35:54 INFO - 'ts_paint', 11:35:54 INFO - 'tpaint', 11:35:54 INFO - 'sessionrestore', 11:35:54 INFO - 'sessionrestore_no_auto_restore', 11:35:54 INFO - 'tabpaint']}, 11:35:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['tsvgx', 11:35:54 INFO - 'tsvgr_opacity', 11:35:54 INFO - 'tart', 11:35:54 INFO - 'tscrollx', 11:35:54 INFO - 'cart']}, 11:35:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:35:54 INFO - 'tsvgr_opacity', 11:35:54 INFO - 'tart', 11:35:54 INFO - 'tscrollx', 11:35:54 INFO - 'cart']}, 11:35:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'talos_options': ['--disable-e10s'], 11:35:54 INFO - 'tests': ['tp5o']}, 11:35:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'tests': ['tp5o']}, 11:35:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'talos_options': ['--disable-e10s', 11:35:54 INFO - '--xperf_path', 11:35:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:35:54 INFO - 'tests': ['tp5n']}, 11:35:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:54 INFO - 'talos_options': ['--xperf_path', 11:35:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:35:54 INFO - 'tests': ['tp5n']}}, 11:35:54 INFO - 'talos.zip': {'path': '', 11:35:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:35:54 INFO - Running post-action listener: _resource_record_post_action 11:35:54 INFO - [mozharness: 2017-02-20 19:35:54.643779Z] Finished populate-webroot step (success) 11:35:54 INFO - [mozharness: 2017-02-20 19:35:54.643892Z] Running create-virtualenv step. 11:35:54 INFO - Running pre-action listener: _resource_record_pre_action 11:35:54 INFO - Running main action method: create_virtualenv 11:35:54 INFO - Creating virtualenv /builds/slave/test/build/venv 11:35:54 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 11:35:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:35:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:35:54 INFO - Using real prefix '/tools/python27' 11:35:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:35:55 INFO - Installing distribute.............................................................................................................................................................................................done. 11:35:58 INFO - Installing pip.................done. 11:35:58 INFO - Return code: 0 11:35:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:35:58 INFO - Reading from file tmpfile_stdout 11:35:58 INFO - Output received: 11:35:58 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) 11:35:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:35:58 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')]} 11:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:58 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')]} 11:35:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:58 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:35:58 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 11:35:58 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 11:35:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:35:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:58 INFO - 'HOME': '/Users/cltbld', 11:35:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:58 INFO - 'LOGNAME': 'cltbld', 11:35:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:58 INFO - 'MOZ_NO_REMOTE': '1', 11:35:58 INFO - 'NO_EM_RESTART': '1', 11:35:58 INFO - 'PAGER': '/bin/cat', 11:35:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:58 INFO - 'PWD': '/builds/slave/test', 11:35:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:58 INFO - 'SHELL': '/bin/bash', 11:35:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:35:58 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:35:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:58 INFO - 'USER': 'cltbld', 11:35:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:58 INFO - 'XPC_FLAGS': '0x0', 11:35:58 INFO - 'XPC_SERVICE_NAME': '0', 11:35:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:59 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 11:35:59 INFO - Cleaning up... 11:35:59 INFO - Return code: 0 11:35:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:35:59 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')]} 11:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:59 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')]} 11:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:59 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:35:59 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 11:35:59 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 11:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:59 INFO - 'HOME': '/Users/cltbld', 11:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:59 INFO - 'LOGNAME': 'cltbld', 11:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:59 INFO - 'MOZ_NO_REMOTE': '1', 11:35:59 INFO - 'NO_EM_RESTART': '1', 11:35:59 INFO - 'PAGER': '/bin/cat', 11:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:59 INFO - 'PWD': '/builds/slave/test', 11:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:59 INFO - 'SHELL': '/bin/bash', 11:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:35:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:59 INFO - 'USER': 'cltbld', 11:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:59 INFO - 'XPC_FLAGS': '0x0', 11:35:59 INFO - 'XPC_SERVICE_NAME': '0', 11:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:59 INFO - Downloading/unpacking psutil>=3.1.1 11:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:36:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:36:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:36:03 INFO - Installing collected packages: psutil 11:36:03 INFO - Running setup.py install for psutil 11:36:03 INFO - building 'psutil._psutil_osx' extension 11:36:03 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 11:36:03 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 11:36:03 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 11:36:03 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 11:36:03 INFO - building 'psutil._psutil_posix' extension 11:36:03 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 11:36:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:36:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:36:04 INFO - ^ 11:36:04 INFO - 1 warning generated. 11:36:04 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 11:36:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:36:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:36:04 INFO - Successfully installed psutil 11:36:04 INFO - Cleaning up... 11:36:04 INFO - Return code: 0 11:36:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:04 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:04 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 11:36:04 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 11:36:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:04 INFO - 'HOME': '/Users/cltbld', 11:36:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:04 INFO - 'LOGNAME': 'cltbld', 11:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:04 INFO - 'MOZ_NO_REMOTE': '1', 11:36:04 INFO - 'NO_EM_RESTART': '1', 11:36:04 INFO - 'PAGER': '/bin/cat', 11:36:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:04 INFO - 'PWD': '/builds/slave/test', 11:36:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:04 INFO - 'SHELL': '/bin/bash', 11:36:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:04 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:04 INFO - 'USER': 'cltbld', 11:36:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:04 INFO - 'XPC_FLAGS': '0x0', 11:36:04 INFO - 'XPC_SERVICE_NAME': '0', 11:36:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:36:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:36:07 INFO - Installing collected packages: mozsystemmonitor 11:36:07 INFO - Running setup.py install for mozsystemmonitor 11:36:07 INFO - Successfully installed mozsystemmonitor 11:36:07 INFO - Cleaning up... 11:36:07 INFO - Return code: 0 11:36:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:07 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:07 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 11:36:07 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 11:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:07 INFO - 'HOME': '/Users/cltbld', 11:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:07 INFO - 'LOGNAME': 'cltbld', 11:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:07 INFO - 'MOZ_NO_REMOTE': '1', 11:36:07 INFO - 'NO_EM_RESTART': '1', 11:36:07 INFO - 'PAGER': '/bin/cat', 11:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:07 INFO - 'PWD': '/builds/slave/test', 11:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:07 INFO - 'SHELL': '/bin/bash', 11:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:07 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:07 INFO - 'USER': 'cltbld', 11:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:07 INFO - 'XPC_FLAGS': '0x0', 11:36:07 INFO - 'XPC_SERVICE_NAME': '0', 11:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:07 INFO - Downloading/unpacking jsonschema==2.5.1 11:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:36:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 INFO - Downloading functools32-3.2.3-2.tar.gz 11:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:36:10 INFO - warning: no files found matching '*.txt' 11:36:10 INFO - no previously-included directories found matching 'build' 11:36:10 INFO - no previously-included directories found matching 'dist' 11:36:10 INFO - no previously-included directories found matching '.git*' 11:36:10 INFO - Installing collected packages: jsonschema, functools32 11:36:10 INFO - Running setup.py install for functools32 11:36:11 INFO - warning: no files found matching '*.txt' 11:36:11 INFO - no previously-included directories found matching 'build' 11:36:11 INFO - no previously-included directories found matching 'dist' 11:36:11 INFO - no previously-included directories found matching '.git*' 11:36:11 INFO - Successfully installed jsonschema functools32 11:36:11 INFO - Cleaning up... 11:36:11 INFO - Return code: 0 11:36:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:36:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:11 INFO - 'HOME': '/Users/cltbld', 11:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:11 INFO - 'LOGNAME': 'cltbld', 11:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:11 INFO - 'MOZ_NO_REMOTE': '1', 11:36:11 INFO - 'NO_EM_RESTART': '1', 11:36:11 INFO - 'PAGER': '/bin/cat', 11:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:11 INFO - 'PWD': '/builds/slave/test', 11:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:11 INFO - 'SHELL': '/bin/bash', 11:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:11 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:11 INFO - 'USER': 'cltbld', 11:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:11 INFO - 'XPC_FLAGS': '0x0', 11:36:11 INFO - 'XPC_SERVICE_NAME': '0', 11:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:36:11 INFO - Cleaning up... 11:36:11 INFO - Return code: 0 11:36:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:36:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:11 INFO - 'HOME': '/Users/cltbld', 11:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:11 INFO - 'LOGNAME': 'cltbld', 11:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:11 INFO - 'MOZ_NO_REMOTE': '1', 11:36:11 INFO - 'NO_EM_RESTART': '1', 11:36:11 INFO - 'PAGER': '/bin/cat', 11:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:11 INFO - 'PWD': '/builds/slave/test', 11:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:11 INFO - 'SHELL': '/bin/bash', 11:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:11 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:11 INFO - 'USER': 'cltbld', 11:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:11 INFO - 'XPC_FLAGS': '0x0', 11:36:11 INFO - 'XPC_SERVICE_NAME': '0', 11:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:11 INFO - Downloading/unpacking blobuploader==1.2.4 11:36:11 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 11:36:11 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 11:36:11 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 11:36:11 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 11:36:11 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 11:36:11 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 11:36:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:36:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:36:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 INFO - Downloading docopt-0.6.1.tar.gz 11:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:36:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:36:15 INFO - Running setup.py install for blobuploader 11:36:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:36:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:36:15 INFO - Running setup.py install for requests 11:36:16 INFO - Running setup.py install for docopt 11:36:16 INFO - Successfully installed blobuploader requests docopt 11:36:16 INFO - Cleaning up... 11:36:16 INFO - Return code: 0 11:36:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36:16 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')]} 11:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:16 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')]} 11:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:16 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:16 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 11:36:16 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 11:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:16 INFO - 'HOME': '/Users/cltbld', 11:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:16 INFO - 'LOGNAME': 'cltbld', 11:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:16 INFO - 'MOZ_NO_REMOTE': '1', 11:36:16 INFO - 'NO_EM_RESTART': '1', 11:36:16 INFO - 'PAGER': '/bin/cat', 11:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:16 INFO - 'PWD': '/builds/slave/test', 11:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:16 INFO - 'SHELL': '/bin/bash', 11:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:16 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:16 INFO - 'USER': 'cltbld', 11:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:16 INFO - 'XPC_FLAGS': '0x0', 11:36:16 INFO - 'XPC_SERVICE_NAME': '0', 11:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:36:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Ji2iTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:36:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TRZlmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:36:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-v6KibE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:36:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-q4RsMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9rgxtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-acnG8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-p4tucj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ktqfy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iwrOsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6oFMJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SbSxza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6die8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:36:17 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-QrYdgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:36:18 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EAMzIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:18 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zrfUEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:36:18 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EpzVFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:36:18 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TXI0ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:36:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:36:18 INFO - Running setup.py install for manifestparser 11:36:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:36:18 INFO - Running setup.py install for mozcrash 11:36:18 INFO - Running setup.py install for mozdebug 11:36:18 INFO - Running setup.py install for mozdevice 11:36:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Running setup.py install for mozfile 11:36:19 INFO - Running setup.py install for mozhttpd 11:36:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Running setup.py install for mozinfo 11:36:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Running setup.py install for mozInstall 11:36:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Running setup.py install for mozleak 11:36:19 INFO - Running setup.py install for mozlog 11:36:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:36:19 INFO - Running setup.py install for moznetwork 11:36:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:36:20 INFO - Running setup.py install for mozprocess 11:36:20 INFO - Running setup.py install for mozprofile 11:36:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:36:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:36:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:36:20 INFO - Running setup.py install for mozrunner 11:36:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:36:20 INFO - Running setup.py install for mozscreenshot 11:36:20 INFO - Running setup.py install for moztest 11:36:20 INFO - Running setup.py install for mozversion 11:36:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:36:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:36:21 INFO - Cleaning up... 11:36:21 INFO - Return code: 0 11:36:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36:21 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')]} 11:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:21 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')]} 11:36:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:21 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:21 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 11:36:21 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 11:36:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:21 INFO - 'HOME': '/Users/cltbld', 11:36:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:21 INFO - 'LOGNAME': 'cltbld', 11:36:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:21 INFO - 'MOZ_NO_REMOTE': '1', 11:36:21 INFO - 'NO_EM_RESTART': '1', 11:36:21 INFO - 'PAGER': '/bin/cat', 11:36:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:21 INFO - 'PWD': '/builds/slave/test', 11:36:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:21 INFO - 'SHELL': '/bin/bash', 11:36:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:21 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:21 INFO - 'USER': 'cltbld', 11:36:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:21 INFO - 'XPC_FLAGS': '0x0', 11:36:21 INFO - 'XPC_SERVICE_NAME': '0', 11:36:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hLh7HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:36:21 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)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-N_F2nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FGnwPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:36:21 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)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bf8rlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:36:21 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)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4gTNGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:36:21 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)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2USYC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:36:21 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)) 11:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:36:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Le_V48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Mq7H8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GJC99b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Cex6pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7oWfnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kZUHbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xX5Dt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-m41MPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kB18Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:22 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)) 11:36:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:36:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1EhJya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:36:23 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-01LOwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:36:23 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)) 11:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:36:23 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)) 11:36:23 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)) 11:36:23 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)) 11:36:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:36: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 11:36: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 11:36: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 11:36: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 11:36: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 11:36: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 11:36:25 INFO - Downloading blessings-1.6.tar.gz 11:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:36:25 INFO - Installing collected packages: blessings 11:36:25 INFO - Running setup.py install for blessings 11:36:25 INFO - Successfully installed blessings 11:36:25 INFO - Cleaning up... 11:36:26 INFO - Return code: 0 11:36:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:36:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:36:26 INFO - Reading from file tmpfile_stdout 11:36:26 INFO - Current package versions: 11:36:26 INFO - blessings == 1.6 11:36:26 INFO - blobuploader == 1.2.4 11:36:26 INFO - docopt == 0.6.1 11:36:26 INFO - functools32 == 3.2.3-2 11:36:26 INFO - jsonschema == 2.5.1 11:36:26 INFO - manifestparser == 1.1 11:36:26 INFO - mozInstall == 1.12 11:36:26 INFO - mozcrash == 1.0 11:36:26 INFO - mozdebug == 0.1 11:36:26 INFO - mozdevice == 0.48 11:36:26 INFO - mozfile == 1.2 11:36:26 INFO - mozhttpd == 0.7 11:36:26 INFO - mozinfo == 0.9 11:36:26 INFO - mozleak == 0.1 11:36:26 INFO - mozlog == 3.4 11:36:26 INFO - moznetwork == 0.27 11:36:26 INFO - mozprocess == 0.23 11:36:26 INFO - mozprofile == 0.28 11:36:26 INFO - mozrunner == 6.13 11:36:26 INFO - mozscreenshot == 0.1 11:36:26 INFO - mozsystemmonitor == 0.3 11:36:26 INFO - moztest == 0.8 11:36:26 INFO - mozversion == 1.4 11:36:26 INFO - psutil == 3.1.1 11:36:26 INFO - requests == 1.2.3 11:36:26 INFO - wsgiref == 0.1.2 11:36:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36: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')]} 11:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36: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')]} 11:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:26 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 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36:26 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 11:36:26 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 11:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:26 INFO - 'HOME': '/Users/cltbld', 11:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:26 INFO - 'LOGNAME': 'cltbld', 11:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:26 INFO - 'MOZ_NO_REMOTE': '1', 11:36:26 INFO - 'NO_EM_RESTART': '1', 11:36:26 INFO - 'PAGER': '/bin/cat', 11:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:26 INFO - 'PWD': '/builds/slave/test', 11:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:26 INFO - 'SHELL': '/bin/bash', 11:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:26 INFO - 'USER': 'cltbld', 11:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:26 INFO - 'XPC_FLAGS': '0x0', 11:36:26 INFO - 'XPC_SERVICE_NAME': '0', 11:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 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)) 11:36:26 INFO - Cleaning up... 11:36:26 INFO - Return code: 0 11:36:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:36: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')]} 11:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36: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')]} 11:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e03f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe08b4c84d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, '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.aQQDfE7hat/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.XUAhrkxQho/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 11:36: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'] in /builds/slave/test/build 11:36: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 11:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:26 INFO - 'HOME': '/Users/cltbld', 11:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:26 INFO - 'LOGNAME': 'cltbld', 11:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:26 INFO - 'MOZ_NO_REMOTE': '1', 11:36:26 INFO - 'NO_EM_RESTART': '1', 11:36:26 INFO - 'PAGER': '/bin/cat', 11:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:26 INFO - 'PWD': '/builds/slave/test', 11:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:26 INFO - 'SHELL': '/bin/bash', 11:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:26 INFO - 'USER': 'cltbld', 11:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:26 INFO - 'XPC_FLAGS': '0x0', 11:36:26 INFO - 'XPC_SERVICE_NAME': '0', 11:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:36:26 INFO - Cleaning up... 11:36:26 INFO - Return code: 0 11:36:26 INFO - Running post-action listener: _resource_record_post_action 11:36:26 INFO - Running post-action listener: _start_resource_monitoring 11:36:26 INFO - Starting resource monitoring. 11:36:26 INFO - [mozharness: 2017-02-20 19:36:26.983424Z] Finished create-virtualenv step (success) 11:36:26 INFO - [mozharness: 2017-02-20 19:36:26.984378Z] Running install step. 11:36:26 INFO - Running pre-action listener: _resource_record_pre_action 11:36:26 INFO - Running main action method: install 11:36:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:36:27 INFO - Reading from file tmpfile_stdout 11:36:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:36:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:36:27 INFO - Reading from file tmpfile_stdout 11:36:27 INFO - Output received: 11:36:27 INFO - Usage: mozinstall [options] installer 11:36:27 INFO - Options: 11:36:27 INFO - -h, --help show this help message and exit 11:36:27 INFO - -d DEST, --destination=DEST 11:36:27 INFO - Directory to install application into. [default: 11:36:27 INFO - "/builds/slave/test"] 11:36:27 INFO - --app=APP Application being installed. [default: firefox] 11:36:27 INFO - mkdir: /builds/slave/test/build/application 11:36:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:36:49 INFO - Reading from file tmpfile_stdout 11:36:49 INFO - Output received: 11:36:49 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:36:49 INFO - Running post-action listener: _resource_record_post_action 11:36:49 INFO - [mozharness: 2017-02-20 19:36:49.105760Z] Finished install step (success) 11:36:49 INFO - [mozharness: 2017-02-20 19:36:49.105889Z] Running run-tests step. 11:36:49 INFO - Running pre-action listener: _resource_record_pre_action 11:36:49 INFO - Running main action method: run_tests 11:36:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:36:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:36:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:36:49 INFO - 2017-02-20 11:36:49.120 screenresolution[1636:8300] starting screenresolution argv=screenresolution get 11:36:49 INFO - 2017-02-20 11:36:49.123 screenresolution[1636:8300] Display 0: 1600x1200x32@60 11:36:49 INFO - 2017-02-20 11:36:49.129 screenresolution[1637:8302] starting screenresolution argv=screenresolution list 11:36:49 INFO - Available Modes on Display 0 11:36:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:36:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:36:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:36:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:36:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:36:49 INFO - Intel Iris: 11:36:49 INFO - Chipset Model: Intel Iris 11:36:49 INFO - Type: GPU 11:36:49 INFO - Bus: Built-In 11:36:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:36:49 INFO - Vendor: Intel (0x8086) 11:36:49 INFO - Device ID: 0x0a2e 11:36:49 INFO - Revision ID: 0x0009 11:36:49 INFO - Displays: 11:36:49 INFO - Display: 11:36:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:36:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:36:49 INFO - Display Serial Number: 1600x1200 60 11:36:49 INFO - Main Display: Yes 11:36:49 INFO - Mirror: Off 11:36:49 INFO - Online: Yes 11:36:49 INFO - Rotation: Supported 11:36:49 INFO - Return code: 0 11:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:36:49 INFO - Python 2.7.3 11:36:49 INFO - Return code: 0 11:36:49 INFO - grabbing minidump binary from tooltool 11:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:49 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 0x10de53d30>, '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 0x10dcb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de4bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:36:49 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 11:36:49 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 11:36:49 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 11:36:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:36:49 INFO - Return code: 0 11:36:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:36:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:36:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:36:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:36:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.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 11:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.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 11:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aQQDfE7hat/Render', 11:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:49 INFO - 'HOME': '/Users/cltbld', 11:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:49 INFO - 'LOGNAME': 'cltbld', 11:36:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:36:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:49 INFO - 'MOZ_NO_REMOTE': '1', 11:36:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:36:49 INFO - 'NO_EM_RESTART': '1', 11:36:49 INFO - 'PAGER': '/bin/cat', 11:36:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:49 INFO - 'PWD': '/builds/slave/test', 11:36:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:49 INFO - 'SHELL': '/bin/bash', 11:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XUAhrkxQho/Listeners', 11:36:49 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:49 INFO - 'USER': 'cltbld', 11:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:49 INFO - 'XPC_FLAGS': '0x0', 11:36:49 INFO - 'XPC_SERVICE_NAME': '0', 11:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.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 11:36:49 INFO - mozversion application_buildid: 20170220070057 11:36:49 INFO - mozversion application_changeset: 3b31bcb88fe341172e93cc8b1143e617c0988694 11:36:49 INFO - mozversion application_display_name: Firefox 11:36:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:36:49 INFO - mozversion application_name: Firefox 11:36:49 INFO - mozversion application_remotingname: firefox 11:36:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:36:49 INFO - mozversion application_vendor: Mozilla 11:36:49 INFO - mozversion application_version: 52.0 11:36:49 INFO - mozversion platform_buildid: 20170220070057 11:36:49 INFO - mozversion platform_changeset: 3b31bcb88fe341172e93cc8b1143e617c0988694 11:36:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:36:49 INFO - mozversion platform_version: 52.0 11:36:49 INFO - using testdate: 1487619409 11:36:49 INFO - actual date: 1487619409 11:36:49 INFO - starting webserver on 'localhost:49235' 11:36:49 INFO - SUITE-START | Running 2 tests 11:36:49 INFO - TEST-START | dromaeo_css 11:36:49 INFO - operating with platform_type : mac_ 11:36:49 INFO - Initialising browser for dromaeo_css test... 11:36:49 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpXldbiK/profile http://localhost:49235/getInfo.html) 11:36:52 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 11:36:52 INFO - PROCESS | 1647 | colorDepth:24 11:36:52 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 11:36:52 INFO - PROCESS | 1647 | __metrics 11:36:52 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:36:52 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:36:53 INFO - TEST-INFO | 1647: exit 0 11:36:53 INFO - Browser initialized. 11:36:53 INFO - Running cycle 1/1 for dromaeo_css test... 11:36:53 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpXldbiK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:05 INFO - PROCESS | 1651 | RSS: Main: 168079360 11:37:05 INFO - PROCESS | 1651 | 11:40:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 11:40:10 INFO - PROCESS | 1651 | RSS: Main: 281747456 11:40:10 INFO - PROCESS | 1651 | 11:43:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 11:43:13 INFO - PROCESS | 1651 | RSS: Main: 302399488 11:43:13 INFO - PROCESS | 1651 | 11:46:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 11:46:16 INFO - PROCESS | 1651 | RSS: Main: 276852736 11:46:16 INFO - PROCESS | 1651 | 11:49:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 11:49:19 INFO - PROCESS | 1651 | RSS: Main: 285159424 11:49:19 INFO - PROCESS | 1651 | 11:52:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 11:52:23 INFO - PROCESS | 1651 | RSS: Main: 276738048 11:52:23 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 11:55:27 INFO - PROCESS | 1651 | RSS: Main: 498008064 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | __start_tp_report 11:55:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:55:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:55:27 INFO - PROCESS | 1651 | |i|pagename|runs| 11:55:27 INFO - PROCESS | 1651 | |0;dojo.html;3489.4591338342557;3500.4749287606874;3523.8414271357788;3528.1885166411726;3565.9821700891493;5869.413058694134;5951.821445356635;5968.19429377034;5990.880182396355;5993.0411134218475;5957.195778569891;6022.0966854971775;6032.547558933075;6037.275526936763;6060.818175454732;5291.444398338167;5304.522592966632;5312.973435132819;5344.821582254242;5469.179623056533;5275.28783614211;5282.815101471449;5289.603279754015;5301.20481927712;5410;5306.044911915854;5393.1101368274185;5411.594130440213;5412.052890744124;5445.455454454563;5383.973080134613;5385.919211211835;5386.13821788512;5391.299131112969;5392.0294347017525;5207.6875387476875;5250.527452529291;5263.842084737464;5305.230741542455;5380.515753582157;5843.298804143487;5912.14273930282;5949.167116603636;6004.219451471325;6061.818145455643;6091.39571768436;6193.845153871144;6225.408586184305;6259.342769009234;6290.3709629037185;6297.4962003039645;6299.181106456132;6312.842178945517;6316.463100636452;6333.018382150728;5840.591158618899;5900.085486749561;5924.348321684612;5963.880722385571;5965.254343207099;6363.204599425072;6363.300036995926;6365.809025729235;6409.102725618417;6421.068945002949;6335.524835637346;6338.619682819046;6342.39747224013;6357.904631430532;6378.77674281398;6242.09489624002;6242.656653883989;6279.623222606659;6300.9684951574945;6328.778492752732;5264.263003179558;6245.594036387711;6252.781152659635;6273;6300.27546832111;6164.907526387108;6205.410486003822;6234.719437625317;6239.407256310643;6249.406306400884;4953.851384458472;5018.172001619765;5064.696118232918;5080.364954380702;5101.107306221396;4528.50186479487;4552.9755804943625;4564.817407303737;4581.175388430114;4584.770761461914;5973.910391344133;5978.402159784074;5990.880182396328;5999.580029397987;6003.609765365237;6240.968795156086;6262.060690896311;6264.9373506265265;6282;6286.371362863769;5984.820455386345;6083.8479038023515;6084.695765211722;6111.1967345965195;6145;5512.862178445491;5556.583256255797;5584.692841893735;5585.8603534911135;5604.439556044363;6503.6097834130105;6510.348965103642;6520.543561950618;6521.11964884744;6593;6168.352323006199;6180.505559555135;6215;6219;6219.595726277777;5025.824096156617;6434;6442.516811239269;6448.193975753031;6480.384363485461;6255.280642725963;6277.30949595535;6280.309165991828;6289.40250676185;6293.37066293367;6220.595661282002;6252;6265.091561723616;6290.056491526308;6328.9050664240995;6197.380261973766;6315.621062736251;6321.273053598895;6331.9366806331345;6333.96833015832;6091.695415229309;6280.717367718555;6300.9684951574945;6313.494920406265;6318.115463835159;6025.939740602713;6075.939240607538;6146.600470969552;6153.538484613761;6186.1339412483185;6209.627422354673;6233.812985610439;6262.780802671885;6323;6327.620342779263;5548.250986116984;5767.942320576741;6162.445379915829;6171.197744293213;6180.011198208265 11:55:27 INFO - PROCESS | 1651 | |1;ext.html;499.8325560937087;525.4535283305362;539.263904769989;584.8187062010779;595.9821205363841;25295.11467098652;32506.02481925546;32506.18734531635;32545.83727081367;40114;39896.40155397671;42918;42953;42967.78516107423;43039;9466.76333091673;9643.517824108802;9651.80696386072;9654.372465789736;9680.564374603124;8950.776230594221;9159.084091590854;9419.764505887339;9495.71512854612;9518.714438566854;9135.725928222164;9178.403403778733;9191.724248272563;11406.08751299894;11658.009069229125;11651.825222621668;11735.53057877684;11740.471678774476;11744.530218791238;11746.7650646987;11518.481668324945;11597.536098556047;11601.883981160165;11607.593734219261;11640.534378624845;33342;40314.798426007685;40417.79791101055;40532;40559.39160912589;3523.8766643167364;3604.9098772530897;3749.9062523437133;3898.3567711327596;3930.489036425275;29675.258118547208;29699.257518562208;29717.85141074281;31143.377132457223;31266.90565830208;3141.952870706941;3163.1617621330365;3405.1997780521556;3407.1141503209096;3416.009357286384;472342;472419;472790;473748.63125684496;474730;59872.70063649741;60389.396106039254;60406.79186416247;60432.489187770654;60548.091778623355;63723;63910;63910.36089639044;63934;64027.679861600394;16573.005619662857;16785;16788.6642267154;16794.748078778826;16868.156592170344;12895.613131606067;12952;12969.221846689228;12981;12994.350282485839;13742.93128534351;16636;16636.168191590372;16654.50036498907;16713.91643041777;5037.11850426174;5971.910421343683;6002.519798416116;6107.84730381744;6151.84620384494;833.8076550532675;956.220680145679;1031.1956673794452;1075;1117.5306371324064;24557.140500082412;30073.94741184092;30199.547006794917;30207.09378718642;31245.53131703026;785.9371250299962;981.8232718110665;1048.0829274384914;1095.1567293184203;1168.9064874809994;14608.926955365156;14692.045017073857;14772.33524491379;15006.174660393783;15011.699766004618;432365;433906;434031;434443;435221;53680.46319536833;53681.73159134179;53820.73089634527;53926.73036634792;53948.73025634847;1256.1207154991544;1291.761024210524;1350.581319790868;1386.2929905748142;1387.6738966343103;24928.626070608592;26887.92448302124;29504;30105.548416774203;30255.546166807075;39601.80199098986;45869.77065114653;46250.768746156056;46322.6103216904;46376.84057898579;334086;336652;337200;337461;350908;23088.769112308662;30409.88635367598;34963.12592185216;35005.49945005479;35086.64913350936;39195.216095678494;42103;42226;42505.36241956311;44495.55504444914;28014;35599;35700.821495892356;36114.45828312525;36798.8160059198;35346.293074138885;35371.46942795911;36265.637343627284;36533.817330913174;36765.26469470542;34112.82943585266;34701.652983469845;35892;35913.46129808107;36190;31068.689313107483;32123.19692007625;32147.35705285928;32149.35701286008;32422.865199717897;19237.711434328216;23775.52448951046;24087.75912240855;24512.877435612707;24546.754532454448 11:55:27 INFO - PROCESS | 1651 | |2;jquery.html;372.76702061211745;381.1310212715011;386.777602878345;401.1775859488049;409.2245195354803;19162.616747665055;20418.795812041895;20553.177872885066;20768.48078798031;20863.165473381046;20182.990850457456;20428.387148385536;20973.895130524365;21025.264115755912;21240.681389779165;5403.540699040586;5740.885182296342;6014.8797024059495;6712.261651218362;6822.181338239418;6440.194975628046;6632.568883022612;6703.865922681543;6709.630970296632;6714.093597364366;6545.345465453464;6603.70283337251;6606.900896486556;6614.173228346457;6629.270780214172;6348.079528468393;6612.041254018164;6621.6027038377615;6637.137172167587;6704.296048914891;5271.736413179364;5651.971740141273;5865.882682346371;5875.588708790387;5879.412058794129;25894.96420143192;26025.73974260252;26050.478990420273;26142.73857261422;26441.206763797116;2213.302809614966;2325.4070212095976;2356.670066190735;2383.547126046063;2416.565018296706;14777.852221477755;15255.542333730005;15725.685486290324;16040.037597744173;16075.196240188061;1870.9438716838517;1886.9056547172586;1901.9619607607908;1904.9047547622563;2001.3995801259564;75714;82816;113094;119239.80760192461;121535.3923230387;10931.344119352865;11188.552457901755;11458.656240312805;11578.42107894602;11729.472173752201;14295.499657511937;14343.99592028548;14450;14450.205238711762;14461.927690361692;8709;8747.987962768599;8818.735437936872;8920.553972301359;8941.821163576693;8680.30557555394;8696.95651521751;8729.08344623818;8848.46909185451;8932.330075244383;5801.984652685763;6724.159480064992;6907.205671347759;6913.930860691329;6919.584824910521;1870.2612468075306;1911.0731295322037;1911.961760764777;1912.7704675439036;1918.2998205655115;880.6609455359604;932.9533523323806;969.1665167955553;1026.1836549307684;1046.9424181669929;20746.896265518575;21914.23300184486;22837.086516539486;23002.769972300062;23599.88200058989;887.7070566712969;904.7195369435497;909.9454032758056;910.8341323106434;987.8221920054316;11499.712507187387;11597.272522916268;11767.529298828122;11787.823182652268;11937.582184623496;588101;611736.4718587674;614695;634018;638279.6602118997;78611.60694196493;102806;118243;130418;134306;901.6168128545341;958.4201558057414;982.3467394182932;983.1937810994915;1020.4285600063839;15557.611059723598;19207.711884322027;19220.615587688448;19661.705074423608;20026.799732002495;52807.73596132149;53270;53824;53959;54158;77271;78501;81745;82579.5871020641;84091.73862392192;7831.686732530863;8063.717769878026;8078.434509584273;8553.016403113721;8561.35789815754;9183.403078799856;12463;12626.43181056873;12687;13036.152650077714;6735.955926831349;7067.752628657972;9436.480993545421;11089.833652495057;11247.381394023407;10839.458027098772;10971.725706857393;11188.552457901593;11508.769824603629;11671.59149429766;11134.665960021212;11461.484233209692;11587.130965177477;11856.76286474283;11883.287002779862;8178.795530111557;8201.30288925448;8322.54226017575;8431.620577073923;8468.830623387621;9903.405795652285;11143.164262680493;11249.325040497597;11347.978681918667;11590.652280431601 11:55:27 INFO - PROCESS | 1651 | |3;mootools.html;1451.8167693329935;1478.6081688352588;1526.282647155837;1836.2104295153079;1845.0110820470838;7996.160403157664;8419.70531031414;8420.810589246794;8447.23974842264;8452.619632116553;7516.84966300675;7754.0695116586085;8026.879596806052;8253.628586713598;8262.50424974502;2345.8827058647084;2372.181597348912;2382.261498935327;2389.4623709665275;2389.8685572293516;2386.5226954609147;2405.5910495215853;2413.3001429585397;2425.8908349124245;2427.0898413095088;2035.2571311471354;2349.5418393413293;2354.2937118864356;2380.571497130516;2389.6773935518654;2346.530693861226;2349.34218418843;2377.393764590036;2378.8929498172624;2379.0602711928864;2378.060666036906;2400.9159679411136;2401.387646150238;2404.0984630763464;2409.204962362416;8312.376571757142;8367.372447066495;8417.494950303002;8444.746657600283;8445.451045682012;5050.9494905051215;5218.530332270076;5218.660787048868;5221.06020916235;5222.086134926372;3213.453712868818;3300.5874265716784;3562.2163124112913;3568.553930758655;3584.534010578608;1061.3496013698696;1068.1881769854965;1099.8900109989017;1108.2685427617735;1114.2256131988272;10768.892311076866;10886.40124793136;10900.182486313479;10904.781904361947;10913.181511386678;4057.39139129133;4482.484514280834;4655.301704744315;4738.952610473852;4760.119377915097;9511.66709165176;9512.429254244767;9517.143457088896;9522.476263805556;9527.809443811086;5233.738313084406;5235.502627250406;5235.685858848481;5241.711705856214;5250.947490525123;4288.884889928641;4519.163954668397;4762.714237145713;4815.542523460265;4822.469528351879;3846.1538461538416;3860.4595356650093;3913.726039177287;3954.2289253595272;3970.841166353371;1278.3927634373783;1285.6657269110099;1288.5246392880288;1300.3758196065942;1307.1503522710125;792.9682812687428;866.2333834556337;904.4618452021037;905.7871400220943;929.5352323838081;4465.977670111629;5554.222408862763;5778.479936805624;5828.795992140254;6074.271087469532;1004.3421558878946;1011.4538149399242;1018.3024628129755;1038.7714702765531;1085.4301491716753;3122.703343182394;3621.583517895476;3697.741158118906;3820.7964491185744;3865.9226815463535;9642.855357169648;10048;10054.949725251327;10085.243606729378;10097.747556311004;9457.29070319715;9911;9925.057119573628;9937.950310248403;9961.203103751683;7688.346490548366;7875.803104922308;7885.250901164381;7891.881621775678;7894.171112033268;5302.310699609026;5518.613697041169;5532.972335138459;5649.37856835756;5905.291365036223;9205.953970230106;9387.859182112137;9402.858957115786;9403.811923761623;9411.011843756582;8052.83233931065;9042.3670343078;9354.906450935676;9493.193078588398;9499.857502137611;7072.186698529735;7100.650876333687;7159.57042577447;7276.199618042117;7286.16209135935;7775.222477752406;7817.491863028885;7836.09884863248;7839.09850367215;7841.529508229524;7079.681414336264;7120.282948770057;7124.216336203117;7243.891341629986;7275.454340924558;6891.095808621494;6951.409130223988;7018.122734658168;7024.086868707035;7144.749933752294;6599.4720422365135;6733.158355205599;6748.865022699617;6763.357481039294;6768.661566921536;4765;4804.35141255926;4822.855314340575;4875.829345972874;4956.925646115239;4762.333273341805;4862.197737373435;4899.559039686445;4948.505149485023;4951.77717002743 11:55:27 INFO - PROCESS | 1651 | |4;prototype.html;259.88694917710797;308.47405174178044;317.104180689552;320.48722044728436;322.53393845892697;4848.733319667422;4905.33777939978;5050.7222102784335;5074.959633275179;5123.257127716483;4457.576530229623;4622.0524792417555;4674.4624368197665;4984.028114517661;4986.925196122052;3963.9009024774323;3980.4626375439234;4002.959970400288;4016.598340165989;4046.31212693842;3697.5193224880727;3984.681225501967;4006.4959400374764;4017.2568074906235;4019.899502512446;3871.5160604924386;3871.5741268460447;3893.805309734516;3921.686265098785;3925.450436938831;3269.0192942117387;3348.902265404491;3751.587325394218;3844.3464611015916;3899.8050097495297;2890.9710902891125;3414.4878268259713;3416.299658570012;3424.6917777400154;3439.1138014043972;3784.674863361166;4869.540881854641;4982.978489409698;5009.924851127236;5012.27322038306;3724.9942515520656;4394.648428125774;4412.470503539564;4425.446819147606;4450.844220452301;5302.231176479428;5326.7070311132875;5327.6191047042375;5328.8401347959625;5329.653572517774;3113.9065828025196;3711.6845068169237;3718.8140592970512;3744.4383342498572;3856.9614303857165;5227.581793456515;5545.251391062236;5573.498385145356;5575.776968921238;5701;3159.3839201355977;3168.5405616185526;3188.617365916105;3750.83121259544;3768.3970564709516;3559.039059453985;3734.514513113278;3847.576766555677;3848.9230215395537;3886.3004659161056;3564.803935783505;4363.80362883671;4416.138852923713;4442.755648439367;4467.664925130628;4425.5574442556135;5346.278252435949;5351.759170837244;5353.9196912046355;5358.526405238591;1060.7748051001106;1091.7008759576079;1139.584051821096;1219.5731493977037;1225.2832093225384;729.784713509516;769.0463824857248;847.6778824046822;851.2338894994431;858.4076986879033;4621.907561848744;4673.696209746356;4675.836345727883;4680.461746899082;4696.178168820443;4919;5183.2743415921805;5185.870353241124;5198.376194856642;5223;4309.375140604595;4433.5788100131085;4450.085194197805;4677.953220467752;4713.128071306819;2317.188983855637;2371.905123795029;2461.163204510475;2487.3905893056317;2678.424138810165;4629.749967508754;5118.795248190031;5328.92006619901;5335.573154147659;5347.572194224453;4744.4069491313585;5132.794688212504;5181.1969144782615;5270.472952704776;5353.571714262927;4719.433667959866;4992.701897506602;5020.5691377958565;5068.771905264272;5303.257543943851;4008.7751298029875;4919.564815416392;5119.769610367617;5142.614303927296;5173.715445650525;5042.016806722654;5090.88728177802;5123.513266239701;5147.253648221062;5159.896802064013;4537;5099.719515426687;5318.228856815663;5329.173978033411;5353.812616558402;3052.6947305269296;3217.356528694224;3240.1737556922835;3532.1566139258143;3551.982240088783;4181.435506206623;4244.8514301999285;4728.9832685971305;5079.460269865068;5153.768080436314;4457.799399026987;4512.751798651106;4539.137563862855;4765.0946320199055;4985.925211121764;4830.516948305141;4993.750312484289;5032.748362581783;5035.345405097461;5064.5948324134715;3965.524137103566;4525.687550610418;4748.715077095385;5210.505002024802;5228.63399562027;4946.653734238569;5251.53619423487;5320.658910079553;5324.760385782572;5335.67985920846;4981.156971920413;5326.280952071421;5328.280682107866;5333.4133245343755;5337.279467272024 11:55:27 INFO - PROCESS | 1651 | |5;yui.html;621.6036979481139;728.5847067171711;766.6166916541729;767.205941850185;768.4428789127882;14645.340959656814;15264.847351526509;15267.312970916308;15302.693946121084;15312.923435382821;13824.239666818321;13976.371063302151;13993.300334983262;14006.369713362898;14032.859671403283;674.5547938360683;696.0707455546851;831.7089018843386;834.4200780457585;851.1190917400492;845.9027211870622;855.5251835231444;860.9784755381135;865.8354912566624;865.9954452834713;912.4798864647154;916.6012784438757;917.999380675064;918.894327152376;920.8434566123741;907.9001309855911;908.4004556992419;908.7137551671268;909.3225546967518;911.6034524981588;676.0771546838579;676.2020815437781;679.7722762874444;680.323078536859;681.5195287322426;14650.926745366205;14752.746016588602;15025.624359391102;15025.924870375688;15057.849421505864;14320.35558399864;14709.264536773118;14734.336954837057;14758.11451312914;14898.925505372405;11223.719407014809;11528.538858445652;11582.362970036645;12142.878571214264;12211.755764884652;3651.8174091295327;3782.0733920189455;3787.4886890269736;3912.374020156761;3946.842126314944;96021.51989240079;100584;100971;104041;106148.46925765477;9314.580843861906;10896.61861834832;11009.944950275198;11061.834072488919;11531.711707207387;22029.559408811732;22494.887525562594;22542;22560.435989100402;22987.425314367276;6726.293739157318;7429.182789893108;7747.690092396354;7833.686652533835;8008.039035315683;4272.886367149167;4301.118270754488;4303.139372125587;4493.73037617744;4512.165249428866;3329.1177837872983;3446.9827650861585;3447.0348302475345;3455.4989526519016;3459.0141809584147;618.9164462797555;626.2797782550103;628.8427893026743;632.6963057732314;647.870425914819;570.0650932470751;578.7932161443354;625.9217597800275;627.7112528236971;662.1954325494547;6895.827604309832;8189.303909167787;8806.735797926072;8826.735197944072;9022.413543119676;569.4049718044653;575.5021906051236;597.238520885874;630.2437075509407;653.5163978655855;4271.423357846713;4289.806958686867;4306.849260275875;4332.631726303236;4417.558244175557;95753.5212323934;110415.79168416587;116367;116742;124961;67080;67506.32493675001;98821;101776;102689;57302;59521.404785951585;73491;83887;88990;8480.321574274167;8489.95755021221;8513.91486085156;8603.268722158688;8692.174243446863;44360.556394436935;55168.620784479106;55296.44703552913;61177.694111529156;61663.458413538225;76832.61583692046;81905.18094818975;83719.74420383821;86379.5681021616;88703.11296887207;10754.892451075702;10891.237613366988;11012.559497620005;11250.549978000789;11394.37330946806;11828.17202795796;11913.52345906154;12025.158238923541;12025.278483291031;12059.698507537381;10394.530029799156;11056.447177640925;11263.66209013731;11381;11726.296422214695;11168.162387821108;11202.663920082085;11708.414579270831;11791.469383877575;11884.584039558575;11078;11565.537378504765;11579.305241685526;11735.178537502294;11902.523899044287;8996.235319997873;9588.280878933969;9633.181179599813;9661.420314780855;9686.321957463191;8625.956870215608;8688.565571721263;9027;9935.254855885982;9958.601655933971 11:55:27 INFO - PROCESS | 1651 | __end_tp_report 11:55:27 INFO - PROCESS | 1651 | __start_cc_report 11:55:27 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1698 11:55:27 INFO - PROCESS | 1651 | __end_cc_report 11:55:27 INFO - PROCESS | 1651 | __startTimestamp1487620527339__endTimestamp 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:55:27 INFO - PROCESS | 1651 | Number of tests: 6 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5872.45 Median:6145.00 stddev:627.78 (10.2%) stddev-sans-first:601.22 11:55:27 INFO - PROCESS | 1651 | Values: 3489.5 3500.5 3523.8 3528.2 3566.0 5869.4 5951.8 5968.2 5990.9 5993.0 5957.2 6022.1 6032.5 6037.3 6060.8 5291.4 5304.5 5313.0 5344.8 5469.2 5275.3 5282.8 5289.6 5301.2 5410.0 5306.0 5393.1 5411.6 5412.1 5445.5 5384.0 5385.9 5386.1 5391.3 5392.0 5207.7 5250.5 5263.8 5305.2 5380.5 5843.3 5912.1 5949.2 6004.2 6061.8 6091.4 6193.8 6225.4 6259.3 6290.4 6297.5 6299.2 6312.8 6316.5 6333.0 5840.6 5900.1 5924.3 5963.9 5965.3 6363.2 6363.3 6365.8 6409.1 6421.1 6335.5 6338.6 6342.4 6357.9 6378.8 6242.1 6242.7 6279.6 6301.0 6328.8 5264.3 6245.6 6252.8 6273.0 6300.3 6164.9 6205.4 6234.7 6239.4 6249.4 4953.9 5018.2 5064.7 5080.4 5101.1 4528.5 4553.0 4564.8 4581.2 4584.8 5973.9 5978.4 5990.9 5999.6 6003.6 6241.0 6262.1 6264.9 6282.0 6286.4 5984.8 6083.8 6084.7 6111.2 6145.0 5512.9 5556.6 5584.7 5585.9 5604.4 6503.6 6510.3 6520.5 6521.1 6593.0 6168.4 6180.5 6215.0 6219.0 6219.6 5025.8 6434.0 6442.5 6448.2 6480.4 6255.3 6277.3 6280.3 6289.4 6293.4 6220.6 6252.0 6265.1 6290.1 6328.9 6197.4 6315.6 6321.3 6331.9 6334.0 6091.7 6280.7 6301.0 6313.5 6318.1 6025.9 6075.9 6146.6 6153.5 6186.1 6209.6 6233.8 6262.8 6323.0 6327.6 5548.3 5767.9 6162.4 6171.2 6180.0 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:56553.77 Median:177215.94 stddev:111060.66 (62.7%) stddev-sans-first:111292.48 11:55:27 INFO - PROCESS | 1651 | Values: 499.8 525.5 539.3 584.8 596.0 25295.1 32506.0 32506.2 32545.8 40114.0 39896.4 42918.0 42953.0 42967.8 43039.0 9466.8 9643.5 9651.8 9654.4 9680.6 8950.8 9159.1 9419.8 9495.7 9518.7 9135.7 9178.4 9191.7 11406.1 11658.0 11651.8 11735.5 11740.5 11744.5 11746.8 11518.5 11597.5 11601.9 11607.6 11640.5 33342.0 40314.8 40417.8 40532.0 40559.4 3523.9 3604.9 3749.9 3898.4 3930.5 29675.3 29699.3 29717.9 31143.4 31266.9 3142.0 3163.2 3405.2 3407.1 3416.0 472342.0 472419.0 472790.0 473748.6 474730.0 59872.7 60389.4 60406.8 60432.5 60548.1 63723.0 63910.0 63910.4 63934.0 64027.7 16573.0 16785.0 16788.7 16794.7 16868.2 12895.6 12952.0 12969.2 12981.0 12994.4 13742.9 16636.0 16636.2 16654.5 16713.9 5037.1 5971.9 6002.5 6107.8 6151.8 833.8 956.2 1031.2 1075.0 1117.5 24557.1 30073.9 30199.5 30207.1 31245.5 785.9 981.8 1048.1 1095.2 1168.9 14608.9 14692.0 14772.3 15006.2 15011.7 432365.0 433906.0 434031.0 434443.0 435221.0 53680.5 53681.7 53820.7 53926.7 53948.7 1256.1 1291.8 1350.6 1386.3 1387.7 24928.6 26887.9 29504.0 30105.5 30255.5 39601.8 45869.8 46250.8 46322.6 46376.8 334086.0 336652.0 337200.0 337461.0 350908.0 23088.8 30409.9 34963.1 35005.5 35086.6 39195.2 42103.0 42226.0 42505.4 44495.6 28014.0 35599.0 35700.8 36114.5 36798.8 35346.3 35371.5 36265.6 36533.8 36765.3 34112.8 34701.7 35892.0 35913.5 36190.0 31068.7 32123.2 32147.4 32149.4 32422.9 19237.7 23775.5 24087.8 24512.9 24546.8 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35152.32 Median:376.95 stddev:102305.03 (27140.3%) stddev-sans-first:102558.69 11:55:27 INFO - PROCESS | 1651 | Values: 372.8 381.1 386.8 401.2 409.2 19162.6 20418.8 20553.2 20768.5 20863.2 20183.0 20428.4 20973.9 21025.3 21240.7 5403.5 5740.9 6014.9 6712.3 6822.2 6440.2 6632.6 6703.9 6709.6 6714.1 6545.3 6603.7 6606.9 6614.2 6629.3 6348.1 6612.0 6621.6 6637.1 6704.3 5271.7 5652.0 5865.9 5875.6 5879.4 25895.0 26025.7 26050.5 26142.7 26441.2 2213.3 2325.4 2356.7 2383.5 2416.6 14777.9 15255.5 15725.7 16040.0 16075.2 1870.9 1886.9 1902.0 1904.9 2001.4 75714.0 82816.0 113094.0 119239.8 121535.4 10931.3 11188.6 11458.7 11578.4 11729.5 14295.5 14344.0 14450.0 14450.2 14461.9 8709.0 8748.0 8818.7 8920.6 8941.8 8680.3 8697.0 8729.1 8848.5 8932.3 5802.0 6724.2 6907.2 6913.9 6919.6 1870.3 1911.1 1912.0 1912.8 1918.3 880.7 933.0 969.2 1026.2 1046.9 20746.9 21914.2 22837.1 23002.8 23599.9 887.7 904.7 909.9 910.8 987.8 11499.7 11597.3 11767.5 11787.8 11937.6 588101.0 611736.5 614695.0 634018.0 638279.7 78611.6 102806.0 118243.0 130418.0 134306.0 901.6 958.4 982.3 983.2 1020.4 15557.6 19207.7 19220.6 19661.7 20026.8 52807.7 53270.0 53824.0 53959.0 54158.0 77271.0 78501.0 81745.0 82579.6 84091.7 7831.7 8063.7 8078.4 8553.0 8561.4 9183.4 12463.0 12626.4 12687.0 13036.2 6736.0 7067.8 9436.5 11089.8 11247.4 10839.5 10971.7 11188.6 11508.8 11671.6 11134.7 11461.5 11587.1 11856.8 11883.3 8178.8 8201.3 8322.5 8431.6 8468.8 9903.4 11143.2 11249.3 11348.0 11590.7 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5453.78 Median:5134.74 stddev:2947.45 (57.4%) stddev-sans-first:2940.38 11:55:27 INFO - PROCESS | 1651 | Values: 1451.8 1478.6 1526.3 1836.2 1845.0 7996.2 8419.7 8420.8 8447.2 8452.6 7516.8 7754.1 8026.9 8253.6 8262.5 2345.9 2372.2 2382.3 2389.5 2389.9 2386.5 2405.6 2413.3 2425.9 2427.1 2035.3 2349.5 2354.3 2380.6 2389.7 2346.5 2349.3 2377.4 2378.9 2379.1 2378.1 2400.9 2401.4 2404.1 2409.2 8312.4 8367.4 8417.5 8444.7 8445.5 5050.9 5218.5 5218.7 5221.1 5222.1 3213.5 3300.6 3562.2 3568.6 3584.5 1061.3 1068.2 1099.9 1108.3 1114.2 10768.9 10886.4 10900.2 10904.8 10913.2 4057.4 4482.5 4655.3 4739.0 4760.1 9511.7 9512.4 9517.1 9522.5 9527.8 5233.7 5235.5 5235.7 5241.7 5250.9 4288.9 4519.2 4762.7 4815.5 4822.5 3846.2 3860.5 3913.7 3954.2 3970.8 1278.4 1285.7 1288.5 1300.4 1307.2 793.0 866.2 904.5 905.8 929.5 4466.0 5554.2 5778.5 5828.8 6074.3 1004.3 1011.5 1018.3 1038.8 1085.4 3122.7 3621.6 3697.7 3820.8 3865.9 9642.9 10048.0 10054.9 10085.2 10097.7 9457.3 9911.0 9925.1 9938.0 9961.2 7688.3 7875.8 7885.3 7891.9 7894.2 5302.3 5518.6 5533.0 5649.4 5905.3 9206.0 9387.9 9402.9 9403.8 9411.0 8052.8 9042.4 9354.9 9493.2 9499.9 7072.2 7100.7 7159.6 7276.2 7286.2 7775.2 7817.5 7836.1 7839.1 7841.5 7079.7 7120.3 7124.2 7243.9 7275.5 6891.1 6951.4 7018.1 7024.1 7144.7 6599.5 6733.2 6748.9 6763.4 6768.7 4765.0 4804.4 4822.9 4875.8 4956.9 4762.3 4862.2 4899.6 4948.5 4951.8 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4193.67 Median:4676.89 stddev:1280.46 (27.4%) stddev-sans-first:1249.54 11:55:27 INFO - PROCESS | 1651 | Values: 259.9 308.5 317.1 320.5 322.5 4848.7 4905.3 5050.7 5075.0 5123.3 4457.6 4622.1 4674.5 4984.0 4986.9 3963.9 3980.5 4003.0 4016.6 4046.3 3697.5 3984.7 4006.5 4017.3 4019.9 3871.5 3871.6 3893.8 3921.7 3925.5 3269.0 3348.9 3751.6 3844.3 3899.8 2891.0 3414.5 3416.3 3424.7 3439.1 3784.7 4869.5 4983.0 5009.9 5012.3 3725.0 4394.6 4412.5 4425.4 4450.8 5302.2 5326.7 5327.6 5328.8 5329.7 3113.9 3711.7 3718.8 3744.4 3857.0 5227.6 5545.3 5573.5 5575.8 5701.0 3159.4 3168.5 3188.6 3750.8 3768.4 3559.0 3734.5 3847.6 3848.9 3886.3 3564.8 4363.8 4416.1 4442.8 4467.7 4425.6 5346.3 5351.8 5353.9 5358.5 1060.8 1091.7 1139.6 1219.6 1225.3 729.8 769.0 847.7 851.2 858.4 4621.9 4673.7 4675.8 4680.5 4696.2 4919.0 5183.3 5185.9 5198.4 5223.0 4309.4 4433.6 4450.1 4678.0 4713.1 2317.2 2371.9 2461.2 2487.4 2678.4 4629.7 5118.8 5328.9 5335.6 5347.6 4744.4 5132.8 5181.2 5270.5 5353.6 4719.4 4992.7 5020.6 5068.8 5303.3 4008.8 4919.6 5119.8 5142.6 5173.7 5042.0 5090.9 5123.5 5147.3 5159.9 4537.0 5099.7 5318.2 5329.2 5353.8 3052.7 3217.4 3240.2 3532.2 3552.0 4181.4 4244.9 4729.0 5079.5 5153.8 4457.8 4512.8 4539.1 4765.1 4985.9 4830.5 4993.8 5032.7 5035.3 5064.6 3965.5 4525.7 4748.7 5210.5 5228.6 4946.7 5251.5 5320.7 5324.8 5335.7 4981.2 5326.3 5328.3 5333.4 5337.3 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:20571.89 Median:55232.53 stddev:30882.01 (55.9%) stddev-sans-first:30932.31 11:55:27 INFO - PROCESS | 1651 | Values: 621.6 728.6 766.6 767.2 768.4 14645.3 15264.8 15267.3 15302.7 15312.9 13824.2 13976.4 13993.3 14006.4 14032.9 674.6 696.1 831.7 834.4 851.1 845.9 855.5 861.0 865.8 866.0 912.5 916.6 918.0 918.9 920.8 907.9 908.4 908.7 909.3 911.6 676.1 676.2 679.8 680.3 681.5 14650.9 14752.7 15025.6 15025.9 15057.8 14320.4 14709.3 14734.3 14758.1 14898.9 11223.7 11528.5 11582.4 12142.9 12211.8 3651.8 3782.1 3787.5 3912.4 3946.8 96021.5 100584.0 100971.0 104041.0 106148.5 9314.6 10896.6 11009.9 11061.8 11531.7 22029.6 22494.9 22542.0 22560.4 22987.4 6726.3 7429.2 7747.7 7833.7 8008.0 4272.9 4301.1 4303.1 4493.7 4512.2 3329.1 3447.0 3447.0 3455.5 3459.0 618.9 626.3 628.8 632.7 647.9 570.1 578.8 625.9 627.7 662.2 6895.8 8189.3 8806.7 8826.7 9022.4 569.4 575.5 597.2 630.2 653.5 4271.4 4289.8 4306.8 4332.6 4417.6 95753.5 110415.8 116367.0 116742.0 124961.0 67080.0 67506.3 98821.0 101776.0 102689.0 57302.0 59521.4 73491.0 83887.0 88990.0 8480.3 8490.0 8513.9 8603.3 8692.2 44360.6 55168.6 55296.4 61177.7 61663.5 76832.6 81905.2 83719.7 86379.6 88703.1 10754.9 10891.2 11012.6 11250.5 11394.4 11828.2 11913.5 12025.2 12025.3 12059.7 10394.5 11056.4 11263.7 11381.0 11726.3 11168.2 11202.7 11708.4 11791.5 11884.6 11078.0 11565.5 11579.3 11735.2 11902.5 8996.2 9588.3 9633.2 9661.4 9686.3 8626.0 8688.6 9027.0 9935.3 9958.6 11:55:27 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:55:27 INFO - PROCESS | 1651 | 11:55:27 INFO - TEST-INFO | 1651: exit 0 11:55:27 INFO - TEST-OK | dromaeo_css | took 1118268ms 11:55:27 INFO - TEST-START | kraken 11:55:27 INFO - operating with platform_type : mac_ 11:55:27 INFO - Initialising browser for kraken test... 11:55:28 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpyXIKym/profile http://localhost:49235/getInfo.html) 11:55:30 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 11:55:30 INFO - PROCESS | 1657 | colorDepth:24 11:55:30 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 11:55:30 INFO - PROCESS | 1657 | __metrics 11:55:30 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:55:30 INFO - PROCESS | 1657 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:55:31 INFO - TEST-INFO | 1657: exit 0 11:55:31 INFO - Browser initialized. 11:55:31 INFO - Running cycle 1/1 for kraken test... 11:55:31 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpyXIKym/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:55:43 INFO - PROCESS | 1659 | RSS: Main: 172388352 11:55:43 INFO - PROCESS | 1659 | 11:55:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 11:55:47 INFO - PROCESS | 1659 | RSS: Main: 222072832 11:55:47 INFO - PROCESS | 1659 | 11:55:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 11:55:50 INFO - PROCESS | 1659 | RSS: Main: 235003904 11:55:50 INFO - PROCESS | 1659 | 11:55:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 11:55:54 INFO - PROCESS | 1659 | RSS: Main: 332304384 11:55:54 INFO - PROCESS | 1659 | 11:55:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 11:55:57 INFO - PROCESS | 1659 | RSS: Main: 233316352 11:55:57 INFO - PROCESS | 1659 | 11:55:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:55:59 INFO - PROCESS | 1659 | RSS: Main: 235888640 11:55:59 INFO - PROCESS | 1659 | 11:56:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 11:56:03 INFO - PROCESS | 1659 | RSS: Main: 275456000 11:56:03 INFO - PROCESS | 1659 | 11:56:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 11:56:06 INFO - PROCESS | 1659 | RSS: Main: 277708800 11:56:06 INFO - PROCESS | 1659 | 11:56:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 11:56:10 INFO - PROCESS | 1659 | RSS: Main: 274116608 11:56:10 INFO - PROCESS | 1659 | 11:56:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:56:12 INFO - PROCESS | 1659 | RSS: Main: 249999360 11:56:12 INFO - PROCESS | 1659 | 11:56:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:56:14 INFO - PROCESS | 1659 | RSS: Main: 252694528 11:56:14 INFO - PROCESS | 1659 | 11:56:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:56:17 INFO - PROCESS | 1659 | RSS: Main: 254607360 11:56:17 INFO - PROCESS | 1659 | 11:56:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:56:21 INFO - PROCESS | 1659 | RSS: Main: 267722752 11:56:21 INFO - PROCESS | 1659 | 11:56:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:56:24 INFO - PROCESS | 1659 | RSS: Main: 267071488 11:56:24 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 11:56:26 INFO - PROCESS | 1659 | RSS: Main: 310935552 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | __start_tp_report 11:56:26 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 11:56:26 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 11:56:26 INFO - PROCESS | 1659 | |i|pagename|runs| 11:56:26 INFO - PROCESS | 1659 | |0;ai-astar;116;114;115;114;114;115;114;115;115;113 11:56:26 INFO - PROCESS | 1659 | |1;audio-beat-detection;137;109;131;173;110;108;108;107;136;106 11:56:26 INFO - PROCESS | 1659 | |2;audio-dft;182;182;183;182;183;184;182;184;183;185 11:56:26 INFO - PROCESS | 1659 | |3;audio-fft;79;75;82;115;61;60;60;58;80;57 11:56:26 INFO - PROCESS | 1659 | |4;audio-oscillator;87;73;93;70;68;68;88;69;69;92 11:56:26 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;87;91;88;90;87;91;89;91;87 11:56:26 INFO - PROCESS | 1659 | |6;imaging-darkroom;102;96;142;161;98;99;98;98;101;98 11:56:26 INFO - PROCESS | 1659 | |7;imaging-desaturate;82;81;79;80;80;81;83;84;82;87 11:56:26 INFO - PROCESS | 1659 | |8;json-parse-financial;56;55;54;53;55;57;57;58;58;57 11:56:26 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;69;70;64;77;71;67;67;69;69;69 11:56:26 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;79;80;92;86;76;80;82;83;108 11:56:26 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;138;133;135;137;136;142;138;141;178;156 11:56:26 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;162;162;169;163;162;163;162;161;162;160 11:56:26 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;66;67;66;65;67;66;65;65;64 11:56:26 INFO - PROCESS | 1659 | __end_tp_report 11:56:26 INFO - PROCESS | 1659 | __start_cc_report 11:56:26 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2049 11:56:26 INFO - PROCESS | 1659 | __end_cc_report 11:56:26 INFO - PROCESS | 1659 | __startTimestamp1487620586562__endTimestamp 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | ------- Summary: start ------- 11:56:26 INFO - PROCESS | 1659 | Number of tests: 14 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:0.85 (0.7%) stddev-sans-first:0.71 11:56:26 INFO - PROCESS | 1659 | Values: 116.0 114.0 115.0 114.0 114.0 115.0 114.0 115.0 115.0 113.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:120.50 stddev:21.82 (18.1%) stddev-sans-first:22.51 11:56:26 INFO - PROCESS | 1659 | Values: 137.0 109.0 131.0 173.0 110.0 108.0 108.0 107.0 136.0 106.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.05 (0.6%) stddev-sans-first:1.05 11:56:26 INFO - PROCESS | 1659 | Values: 182.0 182.0 183.0 182.0 183.0 184.0 182.0 184.0 183.0 185.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:72.70 Median:68.00 stddev:17.94 (26.4%) stddev-sans-first:18.88 11:56:26 INFO - PROCESS | 1659 | Values: 79.0 75.0 82.0 115.0 61.0 60.0 60.0 58.0 80.0 57.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.00 stddev:10.81 (13.5%) stddev-sans-first:10.93 11:56:26 INFO - PROCESS | 1659 | Values: 87.0 73.0 93.0 70.0 68.0 68.0 88.0 69.0 69.0 92.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:90.00 stddev:1.73 (1.9%) stddev-sans-first:1.80 11:56:26 INFO - PROCESS | 1659 | Values: 90.0 87.0 91.0 88.0 90.0 87.0 91.0 89.0 91.0 87.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:98.00 stddev:22.75 (23.2%) stddev-sans-first:23.98 11:56:26 INFO - PROCESS | 1659 | Values: 102.0 96.0 142.0 161.0 98.0 99.0 98.0 98.0 101.0 98.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:82.00 stddev:2.33 (2.8%) stddev-sans-first:2.47 11:56:26 INFO - PROCESS | 1659 | Values: 82.0 81.0 79.0 80.0 80.0 81.0 83.0 84.0 82.0 87.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.70 (3.0%) stddev-sans-first:1.80 11:56:26 INFO - PROCESS | 1659 | Values: 56.0 55.0 54.0 53.0 55.0 57.0 57.0 58.0 58.0 57.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:3.36 (4.9%) stddev-sans-first:3.56 11:56:26 INFO - PROCESS | 1659 | Values: 69.0 70.0 64.0 77.0 71.0 67.0 67.0 69.0 69.0 69.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:82.50 stddev:9.21 (11.2%) stddev-sans-first:9.74 11:56:26 INFO - PROCESS | 1659 | Values: 83.0 79.0 80.0 92.0 86.0 76.0 80.0 82.0 83.0 108.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:139.50 stddev:13.73 (9.8%) stddev-sans-first:14.42 11:56:26 INFO - PROCESS | 1659 | Values: 138.0 133.0 135.0 137.0 136.0 142.0 138.0 141.0 178.0 156.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:162.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 11:56:26 INFO - PROCESS | 1659 | Values: 162.0 162.0 169.0 163.0 162.0 163.0 162.0 161.0 162.0 160.0 11:56:26 INFO - PROCESS | 1659 | 11:56:26 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.00 11:56:26 INFO - PROCESS | 1659 | Values: 67.0 66.0 67.0 66.0 65.0 67.0 66.0 65.0 65.0 64.0 11:56:26 INFO - PROCESS | 1659 | -------- Summary: end -------- 11:56:26 INFO - PROCESS | 1659 | 11:56:27 INFO - TEST-INFO | 1659: exit 0 11:56:27 INFO - TEST-OK | kraken | took 59840ms 11:56:27 INFO - SUITE-END | took 1178s 11:56:28 INFO - Completed test suite (00:19:38) 11:56:28 INFO - javascript benchmark 11:56:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.4591338342557, 3500.4749287606874, 3523.8414271357788, 3528.1885166411726, 3565.9821700891493, 5869.413058694134, 5951.821445356635, 5968.19429377034, 5990.880182396355, 5993.0411134218475, 5957.195778569891, 6022.0966854971775, 6032.547558933075, 6037.275526936763, 6060.818175454732, 5291.444398338167, 5304.522592966632, 5312.973435132819, 5344.821582254242, 5469.179623056533, 5275.28783614211, 5282.815101471449, 5289.603279754015, 5301.20481927712, 5410.0, 5306.044911915854, 5393.1101368274185, 5411.594130440213, 5412.052890744124, 5445.455454454563, 5383.973080134613, 5385.919211211835, 5386.13821788512, 5391.299131112969, 5392.0294347017525, 5207.6875387476875, 5250.527452529291, 5263.842084737464, 5305.230741542455, 5380.515753582157, 5843.298804143487, 5912.14273930282, 5949.167116603636, 6004.219451471325, 6061.818145455643, 6091.39571768436, 6193.845153871144, 6225.408586184305, 6259.342769009234, 6290.3709629037185, 6297.4962003039645, 6299.181106456132, 6312.842178945517, 6316.463100636452, 6333.018382150728, 5840.591158618899, 5900.085486749561, 5924.348321684612, 5963.880722385571, 5965.254343207099, 6363.204599425072, 6363.300036995926, 6365.809025729235, 6409.102725618417, 6421.068945002949, 6335.524835637346, 6338.619682819046, 6342.39747224013, 6357.904631430532, 6378.77674281398, 6242.09489624002, 6242.656653883989, 6279.623222606659, 6300.9684951574945, 6328.778492752732, 5264.263003179558, 6245.594036387711, 6252.781152659635, 6273.0, 6300.27546832111, 6164.907526387108, 6205.410486003822, 6234.719437625317, 6239.407256310643, 6249.406306400884, 4953.851384458472, 5018.172001619765, 5064.696118232918, 5080.364954380702, 5101.107306221396, 4528.50186479487, 4552.9755804943625, 4564.817407303737, 4581.175388430114, 4584.770761461914, 5973.910391344133, 5978.402159784074, 5990.880182396328, 5999.580029397987, 6003.609765365237, 6240.968795156086, 6262.060690896311, 6264.9373506265265, 6282.0, 6286.371362863769, 5984.820455386345, 6083.8479038023515, 6084.695765211722, 6111.1967345965195, 6145.0, 5512.862178445491, 5556.583256255797, 5584.692841893735, 5585.8603534911135, 5604.439556044363, 6503.6097834130105, 6510.348965103642, 6520.543561950618, 6521.11964884744, 6593.0, 6168.352323006199, 6180.505559555135, 6215.0, 6219.0, 6219.595726277777, 5025.824096156617, 6434.0, 6442.516811239269, 6448.193975753031, 6480.384363485461, 6255.280642725963, 6277.30949595535, 6280.309165991828, 6289.40250676185, 6293.37066293367, 6220.595661282002, 6252.0, 6265.091561723616, 6290.056491526308, 6328.9050664240995, 6197.380261973766, 6315.621062736251, 6321.273053598895, 6331.9366806331345, 6333.96833015832, 6091.695415229309, 6280.717367718555, 6300.9684951574945, 6313.494920406265, 6318.115463835159, 6025.939740602713, 6075.939240607538, 6146.600470969552, 6153.538484613761, 6186.1339412483185, 6209.627422354673, 6233.812985610439, 6262.780802671885, 6323.0, 6327.620342779263, 5548.250986116984, 5767.942320576741, 6162.445379915829, 6171.197744293213, 6180.011198208265], "value": 5834.428351822317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.8325560937087, 525.4535283305362, 539.263904769989, 584.8187062010779, 595.9821205363841, 25295.11467098652, 32506.02481925546, 32506.18734531635, 32545.83727081367, 40114.0, 39896.40155397671, 42918.0, 42953.0, 42967.78516107423, 43039.0, 9466.76333091673, 9643.517824108802, 9651.80696386072, 9654.372465789736, 9680.564374603124, 8950.776230594221, 9159.084091590854, 9419.764505887339, 9495.71512854612, 9518.714438566854, 9135.725928222164, 9178.403403778733, 9191.724248272563, 11406.08751299894, 11658.009069229125, 11651.825222621668, 11735.53057877684, 11740.471678774476, 11744.530218791238, 11746.7650646987, 11518.481668324945, 11597.536098556047, 11601.883981160165, 11607.593734219261, 11640.534378624845, 33342.0, 40314.798426007685, 40417.79791101055, 40532.0, 40559.39160912589, 3523.8766643167364, 3604.9098772530897, 3749.9062523437133, 3898.3567711327596, 3930.489036425275, 29675.258118547208, 29699.257518562208, 29717.85141074281, 31143.377132457223, 31266.90565830208, 3141.952870706941, 3163.1617621330365, 3405.1997780521556, 3407.1141503209096, 3416.009357286384, 472342.0, 472419.0, 472790.0, 473748.63125684496, 474730.0, 59872.70063649741, 60389.396106039254, 60406.79186416247, 60432.489187770654, 60548.091778623355, 63723.0, 63910.0, 63910.36089639044, 63934.0, 64027.679861600394, 16573.005619662857, 16785.0, 16788.6642267154, 16794.748078778826, 16868.156592170344, 12895.613131606067, 12952.0, 12969.221846689228, 12981.0, 12994.350282485839, 13742.93128534351, 16636.0, 16636.168191590372, 16654.50036498907, 16713.91643041777, 5037.11850426174, 5971.910421343683, 6002.519798416116, 6107.84730381744, 6151.84620384494, 833.8076550532675, 956.220680145679, 1031.1956673794452, 1075.0, 1117.5306371324064, 24557.140500082412, 30073.94741184092, 30199.547006794917, 30207.09378718642, 31245.53131703026, 785.9371250299962, 981.8232718110665, 1048.0829274384914, 1095.1567293184203, 1168.9064874809994, 14608.926955365156, 14692.045017073857, 14772.33524491379, 15006.174660393783, 15011.699766004618, 432365.0, 433906.0, 434031.0, 434443.0, 435221.0, 53680.46319536833, 53681.73159134179, 53820.73089634527, 53926.73036634792, 53948.73025634847, 1256.1207154991544, 1291.761024210524, 1350.581319790868, 1386.2929905748142, 1387.6738966343103, 24928.626070608592, 26887.92448302124, 29504.0, 30105.548416774203, 30255.546166807075, 39601.80199098986, 45869.77065114653, 46250.768746156056, 46322.6103216904, 46376.84057898579, 334086.0, 336652.0, 337200.0, 337461.0, 350908.0, 23088.769112308662, 30409.88635367598, 34963.12592185216, 35005.49945005479, 35086.64913350936, 39195.216095678494, 42103.0, 42226.0, 42505.36241956311, 44495.55504444914, 28014.0, 35599.0, 35700.821495892356, 36114.45828312525, 36798.8160059198, 35346.293074138885, 35371.46942795911, 36265.637343627284, 36533.817330913174, 36765.26469470542, 34112.82943585266, 34701.652983469845, 35892.0, 35913.46129808107, 36190.0, 31068.689313107483, 32123.19692007625, 32147.35705285928, 32149.35701286008, 32422.865199717897, 19237.711434328216, 23775.52448951046, 24087.75912240855, 24512.877435612707, 24546.754532454448], "value": 19105.627126467607, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.76702061211745, 381.1310212715011, 386.777602878345, 401.1775859488049, 409.2245195354803, 19162.616747665055, 20418.795812041895, 20553.177872885066, 20768.48078798031, 20863.165473381046, 20182.990850457456, 20428.387148385536, 20973.895130524365, 21025.264115755912, 21240.681389779165, 5403.540699040586, 5740.885182296342, 6014.8797024059495, 6712.261651218362, 6822.181338239418, 6440.194975628046, 6632.568883022612, 6703.865922681543, 6709.630970296632, 6714.093597364366, 6545.345465453464, 6603.70283337251, 6606.900896486556, 6614.173228346457, 6629.270780214172, 6348.079528468393, 6612.041254018164, 6621.6027038377615, 6637.137172167587, 6704.296048914891, 5271.736413179364, 5651.971740141273, 5865.882682346371, 5875.588708790387, 5879.412058794129, 25894.96420143192, 26025.73974260252, 26050.478990420273, 26142.73857261422, 26441.206763797116, 2213.302809614966, 2325.4070212095976, 2356.670066190735, 2383.547126046063, 2416.565018296706, 14777.852221477755, 15255.542333730005, 15725.685486290324, 16040.037597744173, 16075.196240188061, 1870.9438716838517, 1886.9056547172586, 1901.9619607607908, 1904.9047547622563, 2001.3995801259564, 75714.0, 82816.0, 113094.0, 119239.80760192461, 121535.3923230387, 10931.344119352865, 11188.552457901755, 11458.656240312805, 11578.42107894602, 11729.472173752201, 14295.499657511937, 14343.99592028548, 14450.0, 14450.205238711762, 14461.927690361692, 8709.0, 8747.987962768599, 8818.735437936872, 8920.553972301359, 8941.821163576693, 8680.30557555394, 8696.95651521751, 8729.08344623818, 8848.46909185451, 8932.330075244383, 5801.984652685763, 6724.159480064992, 6907.205671347759, 6913.930860691329, 6919.584824910521, 1870.2612468075306, 1911.0731295322037, 1911.961760764777, 1912.7704675439036, 1918.2998205655115, 880.6609455359604, 932.9533523323806, 969.1665167955553, 1026.1836549307684, 1046.9424181669929, 20746.896265518575, 21914.23300184486, 22837.086516539486, 23002.769972300062, 23599.88200058989, 887.7070566712969, 904.7195369435497, 909.9454032758056, 910.8341323106434, 987.8221920054316, 11499.712507187387, 11597.272522916268, 11767.529298828122, 11787.823182652268, 11937.582184623496, 588101.0, 611736.4718587674, 614695.0, 634018.0, 638279.6602118997, 78611.60694196493, 102806.0, 118243.0, 130418.0, 134306.0, 901.6168128545341, 958.4201558057414, 982.3467394182932, 983.1937810994915, 1020.4285600063839, 15557.611059723598, 19207.711884322027, 19220.615587688448, 19661.705074423608, 20026.799732002495, 52807.73596132149, 53270.0, 53824.0, 53959.0, 54158.0, 77271.0, 78501.0, 81745.0, 82579.5871020641, 84091.73862392192, 7831.686732530863, 8063.717769878026, 8078.434509584273, 8553.016403113721, 8561.35789815754, 9183.403078799856, 12463.0, 12626.43181056873, 12687.0, 13036.152650077714, 6735.955926831349, 7067.752628657972, 9436.480993545421, 11089.833652495057, 11247.381394023407, 10839.458027098772, 10971.725706857393, 11188.552457901593, 11508.769824603629, 11671.59149429766, 11134.665960021212, 11461.484233209692, 11587.130965177477, 11856.76286474283, 11883.287002779862, 8178.795530111557, 8201.30288925448, 8322.54226017575, 8431.620577073923, 8468.830623387621, 9903.405795652285, 11143.164262680493, 11249.325040497597, 11347.978681918667, 11590.652280431601], "value": 9900.31430416647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.8167693329935, 1478.6081688352588, 1526.282647155837, 1836.2104295153079, 1845.0110820470838, 7996.160403157664, 8419.70531031414, 8420.810589246794, 8447.23974842264, 8452.619632116553, 7516.84966300675, 7754.0695116586085, 8026.879596806052, 8253.628586713598, 8262.50424974502, 2345.8827058647084, 2372.181597348912, 2382.261498935327, 2389.4623709665275, 2389.8685572293516, 2386.5226954609147, 2405.5910495215853, 2413.3001429585397, 2425.8908349124245, 2427.0898413095088, 2035.2571311471354, 2349.5418393413293, 2354.2937118864356, 2380.571497130516, 2389.6773935518654, 2346.530693861226, 2349.34218418843, 2377.393764590036, 2378.8929498172624, 2379.0602711928864, 2378.060666036906, 2400.9159679411136, 2401.387646150238, 2404.0984630763464, 2409.204962362416, 8312.376571757142, 8367.372447066495, 8417.494950303002, 8444.746657600283, 8445.451045682012, 5050.9494905051215, 5218.530332270076, 5218.660787048868, 5221.06020916235, 5222.086134926372, 3213.453712868818, 3300.5874265716784, 3562.2163124112913, 3568.553930758655, 3584.534010578608, 1061.3496013698696, 1068.1881769854965, 1099.8900109989017, 1108.2685427617735, 1114.2256131988272, 10768.892311076866, 10886.40124793136, 10900.182486313479, 10904.781904361947, 10913.181511386678, 4057.39139129133, 4482.484514280834, 4655.301704744315, 4738.952610473852, 4760.119377915097, 9511.66709165176, 9512.429254244767, 9517.143457088896, 9522.476263805556, 9527.809443811086, 5233.738313084406, 5235.502627250406, 5235.685858848481, 5241.711705856214, 5250.947490525123, 4288.884889928641, 4519.163954668397, 4762.714237145713, 4815.542523460265, 4822.469528351879, 3846.1538461538416, 3860.4595356650093, 3913.726039177287, 3954.2289253595272, 3970.841166353371, 1278.3927634373783, 1285.6657269110099, 1288.5246392880288, 1300.3758196065942, 1307.1503522710125, 792.9682812687428, 866.2333834556337, 904.4618452021037, 905.7871400220943, 929.5352323838081, 4465.977670111629, 5554.222408862763, 5778.479936805624, 5828.795992140254, 6074.271087469532, 1004.3421558878946, 1011.4538149399242, 1018.3024628129755, 1038.7714702765531, 1085.4301491716753, 3122.703343182394, 3621.583517895476, 3697.741158118906, 3820.7964491185744, 3865.9226815463535, 9642.855357169648, 10048.0, 10054.949725251327, 10085.243606729378, 10097.747556311004, 9457.29070319715, 9911.0, 9925.057119573628, 9937.950310248403, 9961.203103751683, 7688.346490548366, 7875.803104922308, 7885.250901164381, 7891.881621775678, 7894.171112033268, 5302.310699609026, 5518.613697041169, 5532.972335138459, 5649.37856835756, 5905.291365036223, 9205.953970230106, 9387.859182112137, 9402.858957115786, 9403.811923761623, 9411.011843756582, 8052.83233931065, 9042.3670343078, 9354.906450935676, 9493.193078588398, 9499.857502137611, 7072.186698529735, 7100.650876333687, 7159.57042577447, 7276.199618042117, 7286.16209135935, 7775.222477752406, 7817.491863028885, 7836.09884863248, 7839.09850367215, 7841.529508229524, 7079.681414336264, 7120.282948770057, 7124.216336203117, 7243.891341629986, 7275.454340924558, 6891.095808621494, 6951.409130223988, 7018.122734658168, 7024.086868707035, 7144.749933752294, 6599.4720422365135, 6733.158355205599, 6748.865022699617, 6763.357481039294, 6768.661566921536, 4765.0, 4804.35141255926, 4822.855314340575, 4875.829345972874, 4956.925646115239, 4762.333273341805, 4862.197737373435, 4899.559039686445, 4948.505149485023, 4951.77717002743], "value": 4455.7632006232525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.88694917710797, 308.47405174178044, 317.104180689552, 320.48722044728436, 322.53393845892697, 4848.733319667422, 4905.33777939978, 5050.7222102784335, 5074.959633275179, 5123.257127716483, 4457.576530229623, 4622.0524792417555, 4674.4624368197665, 4984.028114517661, 4986.925196122052, 3963.9009024774323, 3980.4626375439234, 4002.959970400288, 4016.598340165989, 4046.31212693842, 3697.5193224880727, 3984.681225501967, 4006.4959400374764, 4017.2568074906235, 4019.899502512446, 3871.5160604924386, 3871.5741268460447, 3893.805309734516, 3921.686265098785, 3925.450436938831, 3269.0192942117387, 3348.902265404491, 3751.587325394218, 3844.3464611015916, 3899.8050097495297, 2890.9710902891125, 3414.4878268259713, 3416.299658570012, 3424.6917777400154, 3439.1138014043972, 3784.674863361166, 4869.540881854641, 4982.978489409698, 5009.924851127236, 5012.27322038306, 3724.9942515520656, 4394.648428125774, 4412.470503539564, 4425.446819147606, 4450.844220452301, 5302.231176479428, 5326.7070311132875, 5327.6191047042375, 5328.8401347959625, 5329.653572517774, 3113.9065828025196, 3711.6845068169237, 3718.8140592970512, 3744.4383342498572, 3856.9614303857165, 5227.581793456515, 5545.251391062236, 5573.498385145356, 5575.776968921238, 5701.0, 3159.3839201355977, 3168.5405616185526, 3188.617365916105, 3750.83121259544, 3768.3970564709516, 3559.039059453985, 3734.514513113278, 3847.576766555677, 3848.9230215395537, 3886.3004659161056, 3564.803935783505, 4363.80362883671, 4416.138852923713, 4442.755648439367, 4467.664925130628, 4425.5574442556135, 5346.278252435949, 5351.759170837244, 5353.9196912046355, 5358.526405238591, 1060.7748051001106, 1091.7008759576079, 1139.584051821096, 1219.5731493977037, 1225.2832093225384, 729.784713509516, 769.0463824857248, 847.6778824046822, 851.2338894994431, 858.4076986879033, 4621.907561848744, 4673.696209746356, 4675.836345727883, 4680.461746899082, 4696.178168820443, 4919.0, 5183.2743415921805, 5185.870353241124, 5198.376194856642, 5223.0, 4309.375140604595, 4433.5788100131085, 4450.085194197805, 4677.953220467752, 4713.128071306819, 2317.188983855637, 2371.905123795029, 2461.163204510475, 2487.3905893056317, 2678.424138810165, 4629.749967508754, 5118.795248190031, 5328.92006619901, 5335.573154147659, 5347.572194224453, 4744.4069491313585, 5132.794688212504, 5181.1969144782615, 5270.472952704776, 5353.571714262927, 4719.433667959866, 4992.701897506602, 5020.5691377958565, 5068.771905264272, 5303.257543943851, 4008.7751298029875, 4919.564815416392, 5119.769610367617, 5142.614303927296, 5173.715445650525, 5042.016806722654, 5090.88728177802, 5123.513266239701, 5147.253648221062, 5159.896802064013, 4537.0, 5099.719515426687, 5318.228856815663, 5329.173978033411, 5353.812616558402, 3052.6947305269296, 3217.356528694224, 3240.1737556922835, 3532.1566139258143, 3551.982240088783, 4181.435506206623, 4244.8514301999285, 4728.9832685971305, 5079.460269865068, 5153.768080436314, 4457.799399026987, 4512.751798651106, 4539.137563862855, 4765.0946320199055, 4985.925211121764, 4830.516948305141, 4993.750312484289, 5032.748362581783, 5035.345405097461, 5064.5948324134715, 3965.524137103566, 4525.687550610418, 4748.715077095385, 5210.505002024802, 5228.63399562027, 4946.653734238569, 5251.53619423487, 5320.658910079553, 5324.760385782572, 5335.67985920846, 4981.156971920413, 5326.280952071421, 5328.280682107866, 5333.4133245343755, 5337.279467272024], "value": 3786.0360162253737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.6036979481139, 728.5847067171711, 766.6166916541729, 767.205941850185, 768.4428789127882, 14645.340959656814, 15264.847351526509, 15267.312970916308, 15302.693946121084, 15312.923435382821, 13824.239666818321, 13976.371063302151, 13993.300334983262, 14006.369713362898, 14032.859671403283, 674.5547938360683, 696.0707455546851, 831.7089018843386, 834.4200780457585, 851.1190917400492, 845.9027211870622, 855.5251835231444, 860.9784755381135, 865.8354912566624, 865.9954452834713, 912.4798864647154, 916.6012784438757, 917.999380675064, 918.894327152376, 920.8434566123741, 907.9001309855911, 908.4004556992419, 908.7137551671268, 909.3225546967518, 911.6034524981588, 676.0771546838579, 676.2020815437781, 679.7722762874444, 680.323078536859, 681.5195287322426, 14650.926745366205, 14752.746016588602, 15025.624359391102, 15025.924870375688, 15057.849421505864, 14320.35558399864, 14709.264536773118, 14734.336954837057, 14758.11451312914, 14898.925505372405, 11223.719407014809, 11528.538858445652, 11582.362970036645, 12142.878571214264, 12211.755764884652, 3651.8174091295327, 3782.0733920189455, 3787.4886890269736, 3912.374020156761, 3946.842126314944, 96021.51989240079, 100584.0, 100971.0, 104041.0, 106148.46925765477, 9314.580843861906, 10896.61861834832, 11009.944950275198, 11061.834072488919, 11531.711707207387, 22029.559408811732, 22494.887525562594, 22542.0, 22560.435989100402, 22987.425314367276, 6726.293739157318, 7429.182789893108, 7747.690092396354, 7833.686652533835, 8008.039035315683, 4272.886367149167, 4301.118270754488, 4303.139372125587, 4493.73037617744, 4512.165249428866, 3329.1177837872983, 3446.9827650861585, 3447.0348302475345, 3455.4989526519016, 3459.0141809584147, 618.9164462797555, 626.2797782550103, 628.8427893026743, 632.6963057732314, 647.870425914819, 570.0650932470751, 578.7932161443354, 625.9217597800275, 627.7112528236971, 662.1954325494547, 6895.827604309832, 8189.303909167787, 8806.735797926072, 8826.735197944072, 9022.413543119676, 569.4049718044653, 575.5021906051236, 597.238520885874, 630.2437075509407, 653.5163978655855, 4271.423357846713, 4289.806958686867, 4306.849260275875, 4332.631726303236, 4417.558244175557, 95753.5212323934, 110415.79168416587, 116367.0, 116742.0, 124961.0, 67080.0, 67506.32493675001, 98821.0, 101776.0, 102689.0, 57302.0, 59521.404785951585, 73491.0, 83887.0, 88990.0, 8480.321574274167, 8489.95755021221, 8513.91486085156, 8603.268722158688, 8692.174243446863, 44360.556394436935, 55168.620784479106, 55296.44703552913, 61177.694111529156, 61663.458413538225, 76832.61583692046, 81905.18094818975, 83719.74420383821, 86379.5681021616, 88703.11296887207, 10754.892451075702, 10891.237613366988, 11012.559497620005, 11250.549978000789, 11394.37330946806, 11828.17202795796, 11913.52345906154, 12025.158238923541, 12025.278483291031, 12059.698507537381, 10394.530029799156, 11056.447177640925, 11263.66209013731, 11381.0, 11726.296422214695, 11168.162387821108, 11202.663920082085, 11708.414579270831, 11791.469383877575, 11884.584039558575, 11078.0, 11565.537378504765, 11579.305241685526, 11735.178537502294, 11902.523899044287, 8996.235319997873, 9588.280878933969, 9633.181179599813, 9661.420314780855, 9686.321957463191, 8625.956870215608, 8688.565571721263, 9027.0, 9935.254855885982, 9958.601655933971], "value": 7184.020570312087, "unit": "score"}], "name": "dromaeo_css", "value": 7151.361187959036, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0, 115.0, 115.0, 113.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 131.0, 173.0, 110.0, 108.0, 108.0, 107.0, 136.0, 106.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 183.0, 182.0, 183.0, 184.0, 182.0, 184.0, 183.0, 185.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 82.0, 115.0, 61.0, 60.0, 60.0, 58.0, 80.0, 57.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 93.0, 70.0, 68.0, 68.0, 88.0, 69.0, 69.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 87.0, 91.0, 89.0, 91.0, 87.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 96.0, 142.0, 161.0, 98.0, 99.0, 98.0, 98.0, 101.0, 98.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 79.0, 80.0, 80.0, 81.0, 83.0, 84.0, 82.0, 87.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 55.0, 57.0, 57.0, 58.0, 58.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 64.0, 77.0, 71.0, 67.0, 67.0, 69.0, 69.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 80.0, 92.0, 86.0, 76.0, 80.0, 82.0, 83.0, 108.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 133.0, 135.0, 137.0, 136.0, 142.0, 138.0, 141.0, 178.0, 156.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 169.0, 163.0, 162.0, 163.0, 162.0, 161.0, 162.0, 160.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 65.0, 67.0, 66.0, 65.0, 65.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1432.6, "alertThreshold": 2.0}]} 11:56:28 INFO - Return code: 0 11:56:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:56:28 INFO - # TBPL SUCCESS # 11:56:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:56:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:56:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:56:28 INFO - 2017-02-20 11:56:28.361 screenresolution[1663:10579] starting screenresolution argv=screenresolution get 11:56:28 INFO - 2017-02-20 11:56:28.364 screenresolution[1663:10579] Display 0: 1600x1200x32@60 11:56:28 INFO - 2017-02-20 11:56:28.370 screenresolution[1664:10582] starting screenresolution argv=screenresolution list 11:56:28 INFO - Available Modes on Display 0 11:56:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:56:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:56:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:56:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:56:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:56:28 INFO - Intel Iris: 11:56:28 INFO - Chipset Model: Intel Iris 11:56:28 INFO - Type: GPU 11:56:28 INFO - Bus: Built-In 11:56:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:56:28 INFO - Vendor: Intel (0x8086) 11:56:28 INFO - Device ID: 0x0a2e 11:56:28 INFO - Revision ID: 0x0009 11:56:28 INFO - Displays: 11:56:28 INFO - Display: 11:56:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:56:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:56:28 INFO - Display Serial Number: 1600x1200 60 11:56:28 INFO - Main Display: Yes 11:56:28 INFO - Mirror: Off 11:56:28 INFO - Online: Yes 11:56:28 INFO - Rotation: Supported 11:56:28 INFO - Return code: 0 11:56:28 INFO - Running post-action listener: _resource_record_post_action 11:56:28 INFO - [mozharness: 2017-02-20 19:56:28.604119Z] Finished run-tests step (success) 11:56:28 INFO - Running post-run listener: _resource_record_post_run 11:56:28 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33844224; Write bytes: 501424640; Read time: 319; Write time: 1752 11:56:28 INFO - TinderboxPrint: CPU usage
53.6% 11:56:28 INFO - TinderboxPrint: I/O read bytes / time
33,844,224 / 319 11:56:28 INFO - TinderboxPrint: I/O write bytes / time
501,424,640 / 1,752 11:56:28 INFO - TinderboxPrint: CPU idle
2,230.0 (46.4%) 11:56:28 INFO - TinderboxPrint: CPU system
962.6 (20.0%) 11:56:28 INFO - TinderboxPrint: CPU user
1,613.1 (33.6%) 11:56:28 INFO - TinderboxPrint: Swap in / out
222,158,848 / 0 11:56:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192719360; Write bytes: 194037760; Read time: 18001; Write time: 422 11:56:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29121536; Write bytes: 305879552; Read time: 280; Write time: 1322 11:56:29 INFO - Running post-run listener: _upload_blobber_files 11:56:29 INFO - Blob upload gear active. 11:56:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:56:29 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 11:56:29 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:56:29 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:56:29 INFO - (blobuploader) - INFO - Open directory for files ... 11:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:56:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:30 INFO - (blobuploader) - INFO - Done attempting. 11:56:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:56:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:56:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:31 INFO - (blobuploader) - INFO - Done attempting. 11:56:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:56:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:56:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:33 INFO - (blobuploader) - INFO - Done attempting. 11:56:33 INFO - (blobuploader) - INFO - Iteration through files over. 11:56:33 INFO - Return code: 0 11:56:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:56:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:56:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f26eb30496e8621ae753bdcbaecfe82ec496e508d11fa0e1a566424877a49a9e67515f2fe1dfb7041580984305e73e2b3fc9d33a714ea924ae0704f726c5c793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fee965ee0ebf48fb00eaf0bca0386358b79111a97a030544940fc4f2629df96dd9c1625b7ca96589ef245b24adfdafe5a175718fba596de3584e5776d82685e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1e016be8f0ec5327d032a0461584c1511da4dd8f89f2be697cd420e9aecd2afe2ac2793214b0be92aac3aa1c973e6cfa6207aab398eb25e547717387807629eb"} 11:56:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:56:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:56:33 INFO - Contents: 11:56:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f26eb30496e8621ae753bdcbaecfe82ec496e508d11fa0e1a566424877a49a9e67515f2fe1dfb7041580984305e73e2b3fc9d33a714ea924ae0704f726c5c793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fee965ee0ebf48fb00eaf0bca0386358b79111a97a030544940fc4f2629df96dd9c1625b7ca96589ef245b24adfdafe5a175718fba596de3584e5776d82685e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1e016be8f0ec5327d032a0461584c1511da4dd8f89f2be697cd420e9aecd2afe2ac2793214b0be92aac3aa1c973e6cfa6207aab398eb25e547717387807629eb"} 11:56:33 INFO - Running post-run listener: copy_logs_to_upload_dir 11:56:33 INFO - Copying logs to upload dir... 11:56:33 INFO - mkdir: /builds/slave/test/build/upload/logs 11:56:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.825873 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-20 11:56:33.815340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 11:56:33.818581) ========= 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.aQQDfE7hat/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.XUAhrkxQho/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/Mozilla-Beta/sha512/f26eb30496e8621ae753bdcbaecfe82ec496e508d11fa0e1a566424877a49a9e67515f2fe1dfb7041580984305e73e2b3fc9d33a714ea924ae0704f726c5c793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fee965ee0ebf48fb00eaf0bca0386358b79111a97a030544940fc4f2629df96dd9c1625b7ca96589ef245b24adfdafe5a175718fba596de3584e5776d82685e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1e016be8f0ec5327d032a0461584c1511da4dd8f89f2be697cd420e9aecd2afe2ac2793214b0be92aac3aa1c973e6cfa6207aab398eb25e547717387807629eb"} build_url:https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'https://queue.taskcluster.net/v1/task/fn36uTVtQhqWCiAOnj-9QQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f26eb30496e8621ae753bdcbaecfe82ec496e508d11fa0e1a566424877a49a9e67515f2fe1dfb7041580984305e73e2b3fc9d33a714ea924ae0704f726c5c793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fee965ee0ebf48fb00eaf0bca0386358b79111a97a030544940fc4f2629df96dd9c1625b7ca96589ef245b24adfdafe5a175718fba596de3584e5776d82685e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1e016be8f0ec5327d032a0461584c1511da4dd8f89f2be697cd420e9aecd2afe2ac2793214b0be92aac3aa1c973e6cfa6207aab398eb25e547717387807629eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 11:56:33.853226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:56:33.853559) ========= 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.aQQDfE7hat/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.XUAhrkxQho/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.004956 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 11:56:33.901926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 11:56:33.902363) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 11:56:33.902649) ========= ========= Total master_lag: 0.24 =========