builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0001 starttime: 1475858200.73 results: success (0) buildid: 20161007064700 builduid: c10fd5d533ca447482b0277cd85ae8ac revision: 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.729970) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.730686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.731170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.756390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.757039) ========= 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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-07 09:36:40-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.3M=0.001s 2016-10-07 09:36:40 (10.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089875 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.870819) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.871211) ========= 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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.021494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:40.920704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 09:36:40.921120) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-07 09:36:40,995 truncating revision to first 12 chars 2016-10-07 09:36:40,995 Setting DEBUG logging. 2016-10-07 09:36:40,995 attempt 1/10 2016-10-07 09:36:40,995 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6ec07eafa41f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-07 09:36:42,563 unpacking tar archive at: fx-team-6ec07eafa41f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.835887 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 09:36:42.785883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:42.786265) ========= script_repo_revision: 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:42.786695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:42.786998) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 09:36:42.806146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-10-07 09:36:42.806874) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:36:42 INFO - MultiFileLogger online at 20161007 09:36:42 in /builds/slave/test 09:36:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:36:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:43 INFO - {'append_to_log': False, 09:36:43 INFO - 'base_work_dir': '/builds/slave/test', 09:36:43 INFO - 'blob_upload_branch': 'Fx-Team', 09:36:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:43 INFO - 'branch': 'Fx-Team', 09:36:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:43 INFO - 'config_files': ('talos/mac_config.py',), 09:36:43 INFO - 'default_actions': ('clobber', 09:36:43 INFO - 'read-buildbot-config', 09:36:43 INFO - 'download-and-extract', 09:36:43 INFO - 'populate-webroot', 09:36:43 INFO - 'create-virtualenv', 09:36:43 INFO - 'install', 09:36:43 INFO - 'run-tests'), 09:36:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:43 INFO - 'download_minidump_stackwalk': True, 09:36:43 INFO - 'download_symbols': 'ondemand', 09:36:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:43 INFO - 'installer_path': 'installer.exe', 09:36:43 INFO - 'log_level': 'info', 09:36:43 INFO - 'log_name': 'talos', 09:36:43 INFO - 'log_to_console': True, 09:36:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:43 INFO - 'opt_config_files': (), 09:36:43 INFO - 'pip_index': False, 09:36:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:43 INFO - 'cmd': ('bash', 09:36:43 INFO - '-c', 09:36:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:43 INFO - 'enabled': True, 09:36:43 INFO - 'halt_on_failure': False, 09:36:43 INFO - 'name': 'check_screen_resolution'},), 09:36:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:43 INFO - 'cmd': ('bash', 09:36:43 INFO - '-c', 09:36:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:43 INFO - 'enabled': True, 09:36:43 INFO - 'halt_on_failure': False, 09:36:43 INFO - 'name': 'check_screen_resolution'},), 09:36:43 INFO - 'run_cmd_checks_enabled': True, 09:36:43 INFO - 'sps_profile': False, 09:36:43 INFO - 'sps_profile_interval': 0, 09:36:43 INFO - 'suite': 'dromaeojs-e10s', 09:36:43 INFO - 'system_bits': '32', 09:36:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:43 INFO - 'title': 't-yosemite-r7-0001', 09:36:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:43 INFO - 'use_talos_json': True, 09:36:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:43 INFO - 'work_dir': 'build'} 09:36:43 INFO - [mozharness: 2016-10-07 16:36:43.008755Z] Running clobber step. 09:36:43 INFO - Running pre-action listener: _resource_record_pre_action 09:36:43 INFO - Running main action method: clobber 09:36:43 INFO - rmtree: /builds/slave/test/build 09:36:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:43 INFO - Running post-action listener: _resource_record_post_action 09:36:43 INFO - [mozharness: 2016-10-07 16:36:43.890447Z] Finished clobber step (success) 09:36:43 INFO - [mozharness: 2016-10-07 16:36:43.890563Z] Running read-buildbot-config step. 09:36:43 INFO - Running pre-action listener: _resource_record_pre_action 09:36:43 INFO - Running main action method: read_buildbot_config 09:36:43 INFO - Using buildbot properties: 09:36:43 INFO - { 09:36:43 INFO - "project": "", 09:36:43 INFO - "product": "firefox", 09:36:43 INFO - "script_repo_revision": "production", 09:36:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:36:43 INFO - "repository": "", 09:36:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:36:43 INFO - "buildid": "20161007064700", 09:36:43 INFO - "pgo_build": "False", 09:36:43 INFO - "basedir": "/builds/slave/test", 09:36:43 INFO - "buildnumber": 7, 09:36:43 INFO - "slavename": "t-yosemite-r7-0001", 09:36:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:36:43 INFO - "platform": "yosemite_r7", 09:36:43 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 09:36:43 INFO - "branch": "fx-team", 09:36:43 INFO - "repo_path": "integration/fx-team", 09:36:43 INFO - "revision": "6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216", 09:36:43 INFO - "stage_platform": "macosx64", 09:36:43 INFO - "builduid": "c10fd5d533ca447482b0277cd85ae8ac", 09:36:43 INFO - "slavebuilddir": "test" 09:36:43 INFO - } 09:36:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:36:43 INFO - Running post-action listener: _resource_record_post_action 09:36:43 INFO - [mozharness: 2016-10-07 16:36:43.893316Z] Finished read-buildbot-config step (success) 09:36:43 INFO - [mozharness: 2016-10-07 16:36:43.893453Z] Running download-and-extract step. 09:36:43 INFO - Running pre-action listener: _resource_record_pre_action 09:36:43 INFO - Running main action method: download_and_extract 09:36:43 INFO - mkdir: /builds/slave/test/build/tests 09:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:43 INFO - trying https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:36:43 INFO - Downloading https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 09:36:45 INFO - Downloaded 1199 bytes. 09:36:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:36:45 INFO - Using the following test package requirements: 09:36:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:36:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:36:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'jsshell-mac.zip'], 09:36:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:36:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:36:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:36:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:36:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:36:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:36:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:36:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:45 INFO - Fetch https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:36:45 INFO - Http code: 200 09:36:45 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 09:36:45 INFO - x-amz-cf-id: oAjCDLPSWhUJB8RPJzcqQUD5_YsFG0TdTA9ZTBJ-XIGiYwC7S9JmLA== 09:36:45 INFO - x-amz-version-id: fo1dSy8.6JSkRlho4KHFwvUFnq9es9NF 09:36:46 INFO - Expected file size: 16483173 09:36:46 INFO - Obtained file size: 16483173 09:36:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:36:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:52 INFO - Fetch https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:36:53 INFO - Http code: 200 09:36:53 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 09:36:53 INFO - x-amz-cf-id: yQO41Wok7rGYd63tjwMC3XlauZ5cP5yQxXEgkBo7PUVn6NudSfULnQ== 09:36:53 INFO - x-amz-version-id: fo1dSy8.6JSkRlho4KHFwvUFnq9es9NF 09:36:53 INFO - Expected file size: 16483173 09:36:53 INFO - Obtained file size: 16483173 09:36:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:36:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:36:59 INFO - Fetch https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:37:00 INFO - Http code: 200 09:37:00 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 09:37:00 INFO - x-amz-cf-id: BAhTkwiOZSFDGWGSKcPfRUY4ELJm5qdWYBpSGSTI2qCfhYSYOyVTgg== 09:37:00 INFO - x-amz-version-id: EbtQcFcIMQeJ2VJH_yfqQ8AcuBbSH6nN 09:37:00 INFO - Expected file size: 14409006 09:37:00 INFO - Obtained file size: 14409006 09:37:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:01 INFO - trying https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:37:01 INFO - Downloading https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:37:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 09:37:04 INFO - Downloaded 111635853 bytes. 09:37:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:37:04 INFO - mkdir: /builds/slave/test/properties 09:37:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:04 INFO - Contents: 09:37:04 INFO - build_url:https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:37:04 INFO - Running post-action listener: _resource_record_post_action 09:37:04 INFO - Running post-action listener: set_extra_try_arguments 09:37:04 INFO - [mozharness: 2016-10-07 16:37:04.770530Z] Finished download-and-extract step (success) 09:37:04 INFO - [mozharness: 2016-10-07 16:37:04.770652Z] Running populate-webroot step. 09:37:04 INFO - Running pre-action listener: _resource_record_pre_action 09:37:04 INFO - Running main action method: populate_webroot 09:37:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:04 INFO - '--tppagecycles', 09:37:04 INFO - '7'], 09:37:04 INFO - 'tests': ['tsvgm']}}, 09:37:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['damp', 'tps']}, 09:37:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'tests': ['damp', 'tps']}, 09:37:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['dromaeo_dom']}, 09:37:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['basic_compositor_video']}, 09:37:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['a11yr', 09:37:04 INFO - 'ts_paint', 09:37:04 INFO - 'tpaint', 09:37:04 INFO - 'sessionrestore', 09:37:04 INFO - 'sessionrestore_no_auto_restore', 09:37:04 INFO - 'tabpaint']}, 09:37:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['tsvgx', 09:37:04 INFO - 'tsvgr_opacity', 09:37:04 INFO - 'tart', 09:37:04 INFO - 'tscrollx', 09:37:04 INFO - 'cart']}, 09:37:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:04 INFO - 'tsvgr_opacity', 09:37:04 INFO - 'tart', 09:37:04 INFO - 'tscrollx', 09:37:04 INFO - 'cart']}, 09:37:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'talos_options': ['--disable-e10s'], 09:37:04 INFO - 'tests': ['tp5o']}, 09:37:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'tests': ['tp5o']}, 09:37:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'talos_options': ['--disable-e10s', 09:37:04 INFO - '--xperf_path', 09:37:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:04 INFO - 'tests': ['tp5n']}, 09:37:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:04 INFO - 'talos_options': ['--xperf_path', 09:37:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:04 INFO - 'tests': ['tp5n']}}, 09:37:04 INFO - 'talos.zip': {'path': '', 09:37:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:04 INFO - Running post-action listener: _resource_record_post_action 09:37:04 INFO - [mozharness: 2016-10-07 16:37:04.789310Z] Finished populate-webroot step (success) 09:37:04 INFO - [mozharness: 2016-10-07 16:37:04.789422Z] Running create-virtualenv step. 09:37:04 INFO - Running pre-action listener: _resource_record_pre_action 09:37:04 INFO - Running main action method: create_virtualenv 09:37:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:37:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:04 INFO - Using real prefix '/tools/python27' 09:37:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:08 INFO - Installing pip.................done. 09:37:08 INFO - Return code: 0 09:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:08 INFO - Reading from file tmpfile_stdout 09:37:08 INFO - Output received: 09:37:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:37:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:08 INFO - 'HOME': '/Users/cltbld', 09:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:08 INFO - 'LOGNAME': 'cltbld', 09:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:08 INFO - 'MOZ_NO_REMOTE': '1', 09:37:08 INFO - 'NO_EM_RESTART': '1', 09:37:08 INFO - 'PAGER': '/bin/cat', 09:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:08 INFO - 'PWD': '/builds/slave/test', 09:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:08 INFO - 'SHELL': '/bin/bash', 09:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:08 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:08 INFO - 'USER': 'cltbld', 09:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:08 INFO - 'XPC_FLAGS': '0x0', 09:37:08 INFO - 'XPC_SERVICE_NAME': '0', 09:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:37:09 INFO - Cleaning up... 09:37:09 INFO - Return code: 0 09:37:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:09 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:09 INFO - 'HOME': '/Users/cltbld', 09:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:09 INFO - 'LOGNAME': 'cltbld', 09:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:09 INFO - 'MOZ_NO_REMOTE': '1', 09:37:09 INFO - 'NO_EM_RESTART': '1', 09:37:09 INFO - 'PAGER': '/bin/cat', 09:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:09 INFO - 'PWD': '/builds/slave/test', 09:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:09 INFO - 'SHELL': '/bin/bash', 09:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:09 INFO - 'USER': 'cltbld', 09:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:09 INFO - 'XPC_FLAGS': '0x0', 09:37:09 INFO - 'XPC_SERVICE_NAME': '0', 09:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:09 INFO - Downloading/unpacking psutil>=3.1.1 09:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:12 INFO - Installing collected packages: psutil 09:37:12 INFO - Running setup.py install for psutil 09:37:12 INFO - building 'psutil._psutil_osx' extension 09:37:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:37:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:37:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:37:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:37:13 INFO - building 'psutil._psutil_posix' extension 09:37:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:37:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:13 INFO - ^ 09:37:13 INFO - 1 warning generated. 09:37:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:37:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:13 INFO - Successfully installed psutil 09:37:13 INFO - Cleaning up... 09:37:13 INFO - Return code: 0 09:37:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:13 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:13 INFO - 'HOME': '/Users/cltbld', 09:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:13 INFO - 'LOGNAME': 'cltbld', 09:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:13 INFO - 'MOZ_NO_REMOTE': '1', 09:37:13 INFO - 'NO_EM_RESTART': '1', 09:37:13 INFO - 'PAGER': '/bin/cat', 09:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:13 INFO - 'PWD': '/builds/slave/test', 09:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:13 INFO - 'SHELL': '/bin/bash', 09:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:13 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:13 INFO - 'USER': 'cltbld', 09:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:13 INFO - 'XPC_FLAGS': '0x0', 09:37:13 INFO - 'XPC_SERVICE_NAME': '0', 09:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:16 INFO - Installing collected packages: mozsystemmonitor 09:37:16 INFO - Running setup.py install for mozsystemmonitor 09:37:16 INFO - Successfully installed mozsystemmonitor 09:37:16 INFO - Cleaning up... 09:37:16 INFO - Return code: 0 09:37:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:16 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:16 INFO - 'HOME': '/Users/cltbld', 09:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:16 INFO - 'LOGNAME': 'cltbld', 09:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:16 INFO - 'MOZ_NO_REMOTE': '1', 09:37:16 INFO - 'NO_EM_RESTART': '1', 09:37:16 INFO - 'PAGER': '/bin/cat', 09:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:16 INFO - 'PWD': '/builds/slave/test', 09:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:16 INFO - 'SHELL': '/bin/bash', 09:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:16 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:16 INFO - 'USER': 'cltbld', 09:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:16 INFO - 'XPC_FLAGS': '0x0', 09:37:16 INFO - 'XPC_SERVICE_NAME': '0', 09:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:19 INFO - warning: no files found matching '*.txt' 09:37:19 INFO - no previously-included directories found matching 'build' 09:37:19 INFO - no previously-included directories found matching 'dist' 09:37:19 INFO - no previously-included directories found matching '.git*' 09:37:19 INFO - Installing collected packages: jsonschema, functools32 09:37:20 INFO - Running setup.py install for functools32 09:37:20 INFO - warning: no files found matching '*.txt' 09:37:20 INFO - no previously-included directories found matching 'build' 09:37:20 INFO - no previously-included directories found matching 'dist' 09:37:20 INFO - no previously-included directories found matching '.git*' 09:37:20 INFO - Successfully installed jsonschema functools32 09:37:20 INFO - Cleaning up... 09:37:20 INFO - Return code: 0 09:37:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:20 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:20 INFO - 'HOME': '/Users/cltbld', 09:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:20 INFO - 'LOGNAME': 'cltbld', 09:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:20 INFO - 'MOZ_NO_REMOTE': '1', 09:37:20 INFO - 'NO_EM_RESTART': '1', 09:37:20 INFO - 'PAGER': '/bin/cat', 09:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:20 INFO - 'PWD': '/builds/slave/test', 09:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:20 INFO - 'SHELL': '/bin/bash', 09:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:20 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:20 INFO - 'USER': 'cltbld', 09:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:20 INFO - 'XPC_FLAGS': '0x0', 09:37:20 INFO - 'XPC_SERVICE_NAME': '0', 09:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:20 INFO - Cleaning up... 09:37:20 INFO - Return code: 0 09:37:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:20 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:20 INFO - 'HOME': '/Users/cltbld', 09:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:20 INFO - 'LOGNAME': 'cltbld', 09:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:20 INFO - 'MOZ_NO_REMOTE': '1', 09:37:20 INFO - 'NO_EM_RESTART': '1', 09:37:20 INFO - 'PAGER': '/bin/cat', 09:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:20 INFO - 'PWD': '/builds/slave/test', 09:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:20 INFO - 'SHELL': '/bin/bash', 09:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:20 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:20 INFO - 'USER': 'cltbld', 09:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:20 INFO - 'XPC_FLAGS': '0x0', 09:37:20 INFO - 'XPC_SERVICE_NAME': '0', 09:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:24 INFO - Downloading docopt-0.6.1.tar.gz 09:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:24 INFO - Running setup.py install for blobuploader 09:37:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:24 INFO - Running setup.py install for requests 09:37:25 INFO - Running setup.py install for docopt 09:37:25 INFO - Successfully installed blobuploader requests docopt 09:37:25 INFO - Cleaning up... 09:37:25 INFO - Return code: 0 09:37:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:25 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:25 INFO - 'HOME': '/Users/cltbld', 09:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:25 INFO - 'LOGNAME': 'cltbld', 09:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:25 INFO - 'MOZ_NO_REMOTE': '1', 09:37:25 INFO - 'NO_EM_RESTART': '1', 09:37:25 INFO - 'PAGER': '/bin/cat', 09:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:25 INFO - 'PWD': '/builds/slave/test', 09:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:25 INFO - 'SHELL': '/bin/bash', 09:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:25 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:25 INFO - 'USER': 'cltbld', 09:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:25 INFO - 'XPC_FLAGS': '0x0', 09:37:25 INFO - 'XPC_SERVICE_NAME': '0', 09:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:25 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-4mifk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:25 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-GTaX56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:25 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ISLVu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-qxSj5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-OARW0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-zXRr4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-IIWNdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-j2Q0CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-tW0ejb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-cmXl3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8niDtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-uZ1o9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:26 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8Zdm59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-dvtGym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-A9i41C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Hn7WAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:27 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-tyfH9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:27 INFO - Running setup.py install for manifestparser 09:37:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:27 INFO - Running setup.py install for mozcrash 09:37:27 INFO - Running setup.py install for mozdebug 09:37:27 INFO - Running setup.py install for mozdevice 09:37:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Running setup.py install for mozfile 09:37:28 INFO - Running setup.py install for mozhttpd 09:37:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Running setup.py install for mozinfo 09:37:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Running setup.py install for mozInstall 09:37:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Running setup.py install for mozleak 09:37:28 INFO - Running setup.py install for mozlog 09:37:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:28 INFO - Running setup.py install for moznetwork 09:37:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:29 INFO - Running setup.py install for mozprocess 09:37:29 INFO - Running setup.py install for mozprofile 09:37:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:29 INFO - Running setup.py install for mozrunner 09:37:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:29 INFO - Running setup.py install for mozscreenshot 09:37:29 INFO - Running setup.py install for moztest 09:37:29 INFO - Running setup.py install for mozversion 09:37:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:30 INFO - Cleaning up... 09:37:30 INFO - Return code: 0 09:37:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:30 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:30 INFO - 'HOME': '/Users/cltbld', 09:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:30 INFO - 'LOGNAME': 'cltbld', 09:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:30 INFO - 'MOZ_NO_REMOTE': '1', 09:37:30 INFO - 'NO_EM_RESTART': '1', 09:37:30 INFO - 'PAGER': '/bin/cat', 09:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:30 INFO - 'PWD': '/builds/slave/test', 09:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:30 INFO - 'SHELL': '/bin/bash', 09:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:30 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:30 INFO - 'USER': 'cltbld', 09:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:30 INFO - 'XPC_FLAGS': '0x0', 09:37:30 INFO - 'XPC_SERVICE_NAME': '0', 09:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-4iPPM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-LSRIqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-9Xo9sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ICquqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:30 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)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-AgwpUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-J5lL6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:30 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ZUEdQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-el15aS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-BoK6qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Em_Z8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-5F6ZBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vHU6kR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:31 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)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Yvl3Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-2hoKNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-znPPxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:31 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vnrSqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:32 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-_XNeRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:32 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)) 09:37:32 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)) 09:37:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - Downloading blessings-1.6.tar.gz 09:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:34 INFO - Installing collected packages: blessings 09:37:34 INFO - Running setup.py install for blessings 09:37:34 INFO - Successfully installed blessings 09:37:34 INFO - Cleaning up... 09:37:34 INFO - Return code: 0 09:37:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:35 INFO - Reading from file tmpfile_stdout 09:37:35 INFO - Current package versions: 09:37:35 INFO - blessings == 1.6 09:37:35 INFO - blobuploader == 1.2.4 09:37:35 INFO - docopt == 0.6.1 09:37:35 INFO - functools32 == 3.2.3-2 09:37:35 INFO - jsonschema == 2.5.1 09:37:35 INFO - manifestparser == 1.1 09:37:35 INFO - mozInstall == 1.12 09:37:35 INFO - mozcrash == 0.17 09:37:35 INFO - mozdebug == 0.1 09:37:35 INFO - mozdevice == 0.48 09:37:35 INFO - mozfile == 1.2 09:37:35 INFO - mozhttpd == 0.7 09:37:35 INFO - mozinfo == 0.9 09:37:35 INFO - mozleak == 0.1 09:37:35 INFO - mozlog == 3.4 09:37:35 INFO - moznetwork == 0.27 09:37:35 INFO - mozprocess == 0.23 09:37:35 INFO - mozprofile == 0.28 09:37:35 INFO - mozrunner == 6.12 09:37:35 INFO - mozscreenshot == 0.1 09:37:35 INFO - mozsystemmonitor == 0.3 09:37:35 INFO - moztest == 0.7 09:37:35 INFO - mozversion == 1.4 09:37:35 INFO - psutil == 3.1.1 09:37:35 INFO - requests == 1.2.3 09:37:35 INFO - wsgiref == 0.1.2 09:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:35 INFO - 'HOME': '/Users/cltbld', 09:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:35 INFO - 'LOGNAME': 'cltbld', 09:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:35 INFO - 'MOZ_NO_REMOTE': '1', 09:37:35 INFO - 'NO_EM_RESTART': '1', 09:37:35 INFO - 'PAGER': '/bin/cat', 09:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:35 INFO - 'PWD': '/builds/slave/test', 09:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:35 INFO - 'SHELL': '/bin/bash', 09:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:35 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:35 INFO - 'USER': 'cltbld', 09:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:35 INFO - 'XPC_FLAGS': '0x0', 09:37:35 INFO - 'XPC_SERVICE_NAME': '0', 09:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:37:35 INFO - Cleaning up... 09:37:35 INFO - Return code: 0 09:37:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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 0x100b46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f871bca9140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, '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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:35 INFO - 'HOME': '/Users/cltbld', 09:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:35 INFO - 'LOGNAME': 'cltbld', 09:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:35 INFO - 'MOZ_NO_REMOTE': '1', 09:37:35 INFO - 'NO_EM_RESTART': '1', 09:37:35 INFO - 'PAGER': '/bin/cat', 09:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:35 INFO - 'PWD': '/builds/slave/test', 09:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:35 INFO - 'SHELL': '/bin/bash', 09:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:37:35 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:35 INFO - 'USER': 'cltbld', 09:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:35 INFO - 'XPC_FLAGS': '0x0', 09:37:35 INFO - 'XPC_SERVICE_NAME': '0', 09:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:35 INFO - Cleaning up... 09:37:35 INFO - Return code: 0 09:37:35 INFO - Running post-action listener: _resource_record_post_action 09:37:35 INFO - Running post-action listener: _start_resource_monitoring 09:37:35 INFO - Starting resource monitoring. 09:37:35 INFO - [mozharness: 2016-10-07 16:37:35.826719Z] Finished create-virtualenv step (success) 09:37:35 INFO - [mozharness: 2016-10-07 16:37:35.827741Z] Running install step. 09:37:35 INFO - Running pre-action listener: _resource_record_pre_action 09:37:35 INFO - Running main action method: install 09:37:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:36 INFO - Reading from file tmpfile_stdout 09:37:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:36 INFO - Reading from file tmpfile_stdout 09:37:36 INFO - Output received: 09:37:36 INFO - Usage: mozinstall [options] installer 09:37:36 INFO - Options: 09:37:36 INFO - -h, --help show this help message and exit 09:37:36 INFO - -d DEST, --destination=DEST 09:37:36 INFO - Directory to install application into. [default: 09:37:36 INFO - "/builds/slave/test"] 09:37:36 INFO - --app=APP Application being installed. [default: firefox] 09:37:36 INFO - mkdir: /builds/slave/test/build/application 09:37:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:38:10 INFO - Reading from file tmpfile_stdout 09:38:10 INFO - Output received: 09:38:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:10 INFO - Running post-action listener: _resource_record_post_action 09:38:10 INFO - [mozharness: 2016-10-07 16:38:10.333927Z] Finished install step (success) 09:38:10 INFO - [mozharness: 2016-10-07 16:38:10.334075Z] Running run-tests step. 09:38:10 INFO - Running pre-action listener: _resource_record_pre_action 09:38:10 INFO - Running main action method: run_tests 09:38:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:10 INFO - 2016-10-07 09:38:10.348 screenresolution[2231:17150] starting screenresolution argv=screenresolution get 09:38:10 INFO - 2016-10-07 09:38:10.351 screenresolution[2231:17150] Display 0: 1600x1200x32@60 09:38:10 INFO - 2016-10-07 09:38:10.357 screenresolution[2232:17152] starting screenresolution argv=screenresolution list 09:38:10 INFO - Available Modes on Display 0 09:38:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:10 INFO - Intel Iris: 09:38:10 INFO - Chipset Model: Intel Iris 09:38:10 INFO - Type: GPU 09:38:10 INFO - Bus: Built-In 09:38:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:10 INFO - Vendor: Intel (0x8086) 09:38:10 INFO - Device ID: 0x0a2e 09:38:10 INFO - Revision ID: 0x0009 09:38:10 INFO - Displays: 09:38:10 INFO - Display: 09:38:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:10 INFO - Display Serial Number: 1600x1200 60 09:38:10 INFO - Main Display: Yes 09:38:10 INFO - Mirror: Off 09:38:10 INFO - Online: Yes 09:38:10 INFO - Rotation: Supported 09:38:10 INFO - Return code: 0 09:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:10 INFO - Python 2.7.3 09:38:10 INFO - Return code: 0 09:38:10 INFO - grabbing minidump binary from tooltool 09:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:10 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 0x10095ad30>, '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 0x100780db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100952b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:38:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:38:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:38:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:10 INFO - Return code: 0 09:38:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ss3qgDtiyf/Render', 09:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:10 INFO - 'HOME': '/Users/cltbld', 09:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:10 INFO - 'LOGNAME': 'cltbld', 09:38:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:10 INFO - 'MOZ_NO_REMOTE': '1', 09:38:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:10 INFO - 'NO_EM_RESTART': '1', 09:38:10 INFO - 'PAGER': '/bin/cat', 09:38:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:10 INFO - 'PWD': '/builds/slave/test', 09:38:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:10 INFO - 'SHELL': '/bin/bash', 09:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zPOT4AR8ca/Listeners', 09:38:10 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 09:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:10 INFO - 'USER': 'cltbld', 09:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:10 INFO - 'XPC_FLAGS': '0x0', 09:38:10 INFO - 'XPC_SERVICE_NAME': '0', 09:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:38:10 INFO - mozversion application_buildid: 20161007064700 09:38:10 INFO - mozversion application_changeset: 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 09:38:10 INFO - mozversion application_display_name: Nightly 09:38:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:10 INFO - mozversion application_name: Firefox 09:38:10 INFO - mozversion application_remotingname: firefox 09:38:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:38:10 INFO - mozversion application_vendor: Mozilla 09:38:10 INFO - mozversion application_version: 52.0a1 09:38:10 INFO - mozversion platform_buildid: 20161007064700 09:38:10 INFO - mozversion platform_changeset: 6ec07eafa41f4eb5d8a3fc9ed8a165b46603b216 09:38:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:38:10 INFO - mozversion platform_version: 52.0a1 09:38:10 INFO - using testdate: 1475858290 09:38:10 INFO - actual date: 1475858290 09:38:10 INFO - starting webserver on 'localhost:52413' 09:38:10 INFO - SUITE-START | Running 2 tests 09:38:10 INFO - TEST-START | dromaeo_css 09:38:10 INFO - operating with platform_type : mac_ 09:38:10 INFO - Initialising browser for dromaeo_css test... 09:38:10 INFO - TEST-INFO | started process 2242 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp1FARi9/profile http://localhost:52413/getInfo.html) 09:38:13 INFO - PROCESS | 2242 | 2016-10-07 09:38:13.658 plugin-container[2244:17306] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 09:38:13 INFO - PROCESS | 2242 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:13 INFO - PROCESS | 2242 | 2016-10-07 09:38:13.660 plugin-container[2244:17306] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 09:38:13 INFO - PROCESS | 2242 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:13 INFO - PROCESS | 2242 | 2016-10-07 09:38:13.661 plugin-container[2244:17306] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:13 INFO - PROCESS | 2242 | __metrics Screen width/height:1600/1200 09:38:13 INFO - PROCESS | 2242 | colorDepth:24 09:38:13 INFO - PROCESS | 2242 | Browser inner width/height: 1024/689 09:38:13 INFO - PROCESS | 2242 | __metrics 09:38:13 ERROR - PROCESS | 2242 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 09:38:14 INFO - TEST-INFO | 2242: exit 0 09:38:14 INFO - Browser initialized. 09:38:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:14 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp1FARi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:17 INFO - PROCESS | 2245 | 2016-10-07 09:38:17.067 plugin-container[2246:17423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.tsm.portname' 09:38:17 INFO - PROCESS | 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:17 INFO - PROCESS | 2245 | 2016-10-07 09:38:17.070 plugin-container[2246:17423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 09:38:17 INFO - PROCESS | 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:17 INFO - PROCESS | 2245 | 2016-10-07 09:38:17.070 plugin-container[2246:17423] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:26 INFO - PROCESS | 2245 | RSS: Main: 239644672 09:38:26 INFO - PROCESS | 2245 | 09:39:16 INFO - PROCESS | 2245 | 1475858356372 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:41:30 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52413/tests/dromaeo/cssquery-ext.html) 09:41:31 INFO - PROCESS | 2245 | RSS: Main: 251301888 09:41:31 INFO - PROCESS | 2245 | 09:44:33 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-ext.html (next: http://localhost:52413/tests/dromaeo/cssquery-jquery.html) 09:44:34 INFO - PROCESS | 2245 | RSS: Main: 265089024 09:44:34 INFO - PROCESS | 2245 | 09:47:36 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52413/tests/dromaeo/cssquery-mootools.html) 09:47:36 INFO - PROCESS | 2245 | RSS: Main: 269901824 09:47:36 INFO - PROCESS | 2245 | 09:50:38 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52413/tests/dromaeo/cssquery-prototype.html) 09:50:39 INFO - PROCESS | 2245 | RSS: Main: 269676544 09:50:39 INFO - PROCESS | 2245 | 09:53:42 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52413/tests/dromaeo/cssquery-yui.html) 09:53:42 INFO - PROCESS | 2245 | RSS: Main: 275038208 09:53:42 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:52413/tests/dromaeo/cssquery-yui.html (next: http://localhost:52413/tests/dromaeo/cssquery-dojo.html) 09:56:45 INFO - PROCESS | 2245 | RSS: Main: 276189184 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | __start_tp_report 09:56:45 INFO - PROCESS | 2245 | _x_x_mozilla_page_load 09:56:45 INFO - PROCESS | 2245 | _x_x_mozilla_page_load_details 09:56:45 INFO - PROCESS | 2245 | |i|pagename|runs| 09:56:45 INFO - PROCESS | 2245 | |0;dojo.html;3345.0633822529685;3367.6969072783454;3399.1672040350118;3402.7958322500635;3410.0281419795365;5642.322921249456;5719.056355701309;5735.627184233027;5783.306003279602;5793.971030144849;5661.433856614337;5685.943140568593;5744.827655170341;5782.335031471382;5867.88264234715;5145.948540514603;5166.018456493273;5229.764660590283;5290.206469029637;5297.417284098746;5251.55361794248;5357.464253574631;5392.946070539284;5393.2449457076045;5469.72651367432;5193.948060519384;5245.606579506514;5284.2866213061125;5294.232336311252;5318.680879147244;5150.407703114152;5207.739613019372;5286.418493965661;5324.840254792362;5341.599380046512;5194.974025129888;5196.194589838581;5205.401378841444;5227.45111763267;5258.947410525922;5572.58205634575;5753.137029445575;5768.740406681667;5778.913316300259;5816.854578635525;5120.974395128038;6138.1099740537375;6141.969290153565;6148.692565371758;6224.62652240867;5284.339457567804;6250.281217659982;6266.624002559815;6269.185005949197;6287.842803929892;4380.2115619188535;5078.806480477085;5443.237946687467;5890.410958904075;5896.439838215363;6103.549811064039;6190.876182476325;6201.6899155042065;6237.438630523184;6382.32985536512;5088.338515992971;5513.255710479034;6126.172966649535;6174.197354343906;6207.379262073757;5847;6126.877462450726;6250.4374606285655;6274.905876411857;6284.214473190851;6116.883633902656;6136.723847426876;6161.952468080437;6164.198654174928;6245.094461303176;5901.675407852609;6246.968765156236;6255.812325630239;6269.310375858652;6273.96863015682;4975.179095449211;4977;4980.402351717817;4980.651354405157;5002.474740152305;4323.610875021263;4412.7352358857925;4424.6681498887065;4501.68488205829;4542.227821270392;5631.831045068655;5874.118882167709;5874.412558744091;5875.177475153397;5994.130851026577;5528.115501519818;6287.49700023997;6291.559590828598;6293.811185664437;6332;5728.570357223225;5859.882802343929;5862.091375836752;5882.176495290662;5893;5397.541208997201;5407.83776486706;5428.75570599324;5465.863353416196;5552.52803511706;5855.770288239432;5980.43185897339;6413.35866413355;6441.871162576722;6444.516661250331;6235.657038862906;6244.843878903063;6264.968675156595;6291.056341548804;6321.367863213642;6196.1635179252025;6259.155014073224;6293.3391993841815;6295.811125666238;6330.556861019684;6217.595856269328;6249.375062493714;6249.468795152463;6252.09344645033;6264.404881536247;6262.060690896402;6267.2792628848265;6274.717637706405;6282.340354262737;6319.968400158154;5745.91381129275;6062.818115456544;6102.337773232528;6169.722362493789;6230.096635987847;6007.48936340416;6134.8159555213415;6189.938100618936;6191.4118158774845;6212.285587157535;5914.852128696817;6008.459238668541;6026.548008899263;6079.300880398634;6089.238845144357;6098;6121.178620693132;6125.601835880653;6144.556029333014;6252.312245652884;6072.575162879847;6110.969445152746;6115.816525504242;6126.050462178369;6130.846728831815 09:56:45 INFO - PROCESS | 2245 | |1;ext.html;472.19019381760273;488.8655619704583;499.1140725212747;524.685188886668;529.0767610519642;33366.998990030304;40382.7980860096;40527.39208911861;40562.79718601407;40596.18807623846;41306.38040429389;41727.79136104315;41785;41881.16237675244;41913.79043104788;7595.886061709079;9612.663556775515;9691.224702023821;9852.50737463124;9874.061964113434;8417.368697347692;8709.520976346299;8778.2099611035;9336.3464557481;9563.521823908777;8282.668693252263;9330.160285574297;9652.179564737005;9680.515974201297;9713.514324283793;7794.610269486503;8189.8771518427275;9599.42403455795;9656.662016829447;9734.318597698093;8193.50838949665;9584.089511496397;9699.70900872975;9702.51487425626;9717.90282097177;39779.80110099431;39787.40318895219;39942.20115597672;40117.398239026435;40232;3303.3888730584677;3309.7683162178655;3322.5680661513848;3557.679808817193;3989.960100398988;30436.391272174653;30745.538816917764;31005;31861.044168674976;31951.840240798647;3186.8725250990165;3231.127595549212;3243.464828303327;3260.1849537615594;3327.4010678077934;484642;496576;501115;501138;501261.4936925328;54737.726311368984;54902;55353.446465535635;55404.44595553993;55466.72266638641;57536.42463575311;57849.1903593018;58274;58827.70586147042;58829;15077.321520531603;15109;15405.22973851303;15426.382944682087;15434.45979390716;11856.585019524275;11894.583689570823;11981.161318707607;11998;12001.39993000364;12765.978721702242;13094.541691040768;14700.852991470163;14755.704885902222;14834.10995340283;3120.563121163039;6203.534734894901;6432.51756118293;6462.386073323027;6462.903056454157;817.9304759095425;909.7718080591148;970.7039352997404;977.6480467031863;1001.104011909337;28492;29292.853535732185;29865.145383133655;29896.850515747283;30044.699553004626;1015.4465816130079;1027.342500799474;1087.4073629871739;1094.6442406218011;1109.606089838112;14920.477783277533;15308.158051307286;15377.538673839803;15421;15703.842961570466;557718;557760;564605;564852;565608;55368.72315638396;55736.442635573934;55891.88216235652;56175.43824561702;56258.718706407024;1315.4343632238229;1347.6226656536185;1356.4302992743071;1359.4698067753782;1362.9931850340686;24261.51476970486;29081.85459072691;29116;29266.41467170602;29846.403071938006;47371.05257894892;47702.284465733734;47765.761171193924;47770;47775.76112119417;515989;610549;612960;613706;614362.8563714306;20925.16299348009;21864.78135218691;25448.872755636843;25729.871350643127;25779.484410311314;40283.597164029154;42963;45547.77226113848;45577.316340254256;46450;36088.63911360958;37056.629433706396;37370;38351.23297533978;38549;30097;37220.81389593035;38053.80973095228;38212.617873820906;38230.42654360242;30419.39161216719;34757.13107172341;36327.45508817317;36493.817530913235;37959;32395.676043239266;33311.666883331825;33904;33904.66095339015;34093.65906341004;19934;22052.448688782908;22775.97508112172;24659.876700616383;25275.620865687397 09:56:45 INFO - PROCESS | 2245 | |2;jquery.html;341.1539382331817;376.8454933477274;376.86244520749915;381.47547122706277;397.96219359160887;16741.91629041856;18425;19242;20056.69914951275;20881.269155579575;11499.482523286451;11911.7617647647;12198.939005304961;12219.816702749444;12232.449539770709;5017.448080711119;5254.369475662926;5289.920651190235;5392.946070539303;5404.729763511828;3902.570717221103;4103.158852435258;5374.435684253156;5423.864403389907;7391.667374968112;6453.451456626192;7795.18150594188;7837.647305871249;7958.801029974239;7972.402069844785;7674.501157424777;7739.264769846855;7768.601651702691;7803.648835802374;7812.023497062867;7415.925840741577;7424.8856386030175;7532.698692052366;7603.885941710879;7603.885941710879;28491.145265642062;28647.71352286492;28763.849446022134;28896.566551501743;29548.96578619738;1952.6387618290662;2361.5158892427185;2379.8096152307776;2388.0686532252435;2590.650262214596;16014.839851601451;17266.65466690659;17389.39137130207;17603.823961760474;17719.73420398695;1986.6424043672134;2022.8280596149345;2029.1173339597322;2061.8556701030784;2077.9168833246804;124651.3767431157;127153;130986;135888;138505.307473463;20768.48078798042;20971;21427.035783389783;21706.565868682854;21885;34690.47964280538;35083.29833403318;35167;35329.470057949155;35346.05596692207;10194.809359136823;10461.26771126029;10661.680149595524;10797.514111864984;10819.459027048773;8658.826823463496;8731;8763.824723505495;8873;8885.333599979898;9563;9854.50727463624;9921.206303495703;9953.900460995443;9994.650187243557;1528.9311980960883;1679.673058283942;1680.411855850467;3260.8858689945737;3802.9429558556644;717.2397258905577;876.8465518534231;912.0605776050678;921.9489781648925;959.9760005999766;17086.40197593078;19618.70571941422;20408.79591204069;20986.2654807081;21139.943002849795;792.2988155482325;796.4703472190928;847.4872702015315;934.1545900959643;935.7239614313794;8657.567121643893;11129.777404451866;11164.776704466027;11188.66434006981;11207.495662695199;450319;579804;580586.0970695204;596246;596979.3020697982;73476;83336;96386;126129;130483;940.9952950235205;970.679675707015;1033.426448321189;1037.4501514197486;1040.15747244732;18282.360117395827;18394.448166555027;18659.160337785102;18764.061796909828;19091;52483;54591.72704136454;54811;55314.72342638261;55471.16793248024;74043;76165;78100.60949695215;79585;80426.54454920822;7142.392896603847;7533.7739867801865;7775.300222979959;8133.959330203311;8493.320534357114;10069.546870390997;11345.773084538097;12370.195937264049;12802.84774370311;13448.1258718183;11137.220394572301;11451;11573.363465009505;11679.124065694938;11701.824472632747;9855.901440985499;10087.445190514592;11094.334339939629;11160.720981975515;11399.97400233983;11477.311361318347;11689.824652630388;11828.76342473163;11891.345975971404;11973;8076.152004039492;8282.50304981703;8482.109378515168;8511.914880851113;8629.35279854026;8282;10732.892671073188;10849.45752712377;11225.438728063727;11399.829002565135 09:56:45 INFO - PROCESS | 2245 | |3;mootools.html;1273.9235346132518;1486.383150992338;1598.6163320803012;1798.9730154047684;1820.6085691576316;8036.236557527026;8288.70989515367;8434.283085937695;8883.244924181445;8996.91003089969;8441.07148213696;8742.562871856398;8753.95623021884;8857.468551886892;8877.866831997526;2348.8120950323932;2369.7748713872197;2386.033656369173;2390.760923907613;2397.7841994220516;2348.6007378745653;2373.738888722248;2376.514678326046;2381.4760752634393;2385.2844146755992;2360;2366.148186652812;2374.8931298091543;2382.0709923129994;2386.2483317754936;2251.8423710340285;2304.3087073877855;2314.9884250578807;2317.096332430362;2319.350581837088;2314.236302020346;2317.7566355532763;2317.9420514487106;2323.314622186442;2332.8017118544944;8812.955935220347;8882.822343553093;8887;8895.911040889638;8902.42134261271;5043.747812609392;5238.685678859242;5241.73791310433;5287.444818294062;5325.946740532584;2876.6548014238215;3215.389076075538;3429.176997520602;3588.7129029677826;3633.6911362534215;1023.3961962442121;1048.2138396202847;1066.306900514664;1079.525008996047;1081.6754973507916;11517.36654484003;11637.709057273636;11653.88346116545;11681.70795730105;11758.470868810924;4566.06395688883;4600.815967361268;4797.976010119997;4818.494058123917;4821.421429428491;9859;9919.454430006275;9936.105750482348;9955.751106222402;9958.452285124387;5306.363236411578;5322.600804939568;5338.91991620126;5341.946580534145;5347.973260133675;4407.096545208289;4541.409616749801;4797.4482934462285;4841.104395686809;4869.610431165498;2892.5661150827123;3320.7841490303053;3355.345707587046;3455.5486695587915;3701.648898152209;1152.9192956492966;1153.2273376837538;1249.181785930217;1276.3809552367095;1307.2026064100892;762.523422860712;845.7843249971218;914.6981496106268;935.7239614313794;950.8573713942964;5627.43725627434;5745.3105627324985;5851.122331650201;5917.289925208916;5964.910526342108;413.57195302861993;416.4024624663617;456.0081822037056;456.4727739460928;458.15241802664804;3839.577646458887;3971.5829837867186;4020.7788571628257;4034.495688038995;4068.6541643960004;9940.850887236697;10638.94680526608;10770.946145269223;10784.838227426595;10820.13438924884;10368.326058806153;10751.408672523086;10774.19193560486;10783.6225732099;10830.67507974762;8083.514989100555;8090.231428014329;8180.14108518597;8220.753377398687;8248.340132789363;5685.431456854447;5806.65160090396;5814.622049566765;5834.649921004753;5867.735951882261;9908.702738917844;9958.800823983625;9976.60093596248;9987.300888937705;10026;9804;9933.056359645821;9972.60109595637;10001.499925003865;10202.081812636898;6859.519833611599;7095.929040709733;7288.67200975968;7453.813654658677;7829.882551761614;8255;8364.247217750431;8445.95777021111;8458.915410845813;8483.151684831468;7382.52013619113;7503.512271702322;7526.924730752841;7552.509086909334;7565.659545320364;7266;7310.926890731024;7322.926770732438;7384.37232835215;7514.323710866049;6879.208890977401;6897.448204143757;6950.687219075053;6961.721531138698;6978.197507286727;4848.151573474698;4887.266909963534;5001.924971125363;5024.522670346311;5054.519820617035;4938.283948827472;4979.427365852828;5064.468230835857;5125.000624878113;5156.2523434102595 09:56:45 INFO - PROCESS | 2245 | |4;prototype.html;296.68551335584283;302.6519502572042;314.9984250078749;319.2529481014426;320.2154720933713;4505.43682039745;4679.1109689159075;5202;5428.972855135724;5558.749856256468;5403.918941215885;5415.9458405415935;5463.016657001738;5472.34331880174;5485.835424937259;4134.498628329862;4173.165366926627;4187.74873507589;4190.434291370657;4195.874123776276;3788.810559472029;4046.615571520701;4151.584841515854;4178.56125106864;4259.552746961555;3383.5601371821754;3931.842726290945;4118.938215926763;4120.278951183546;4137.731047481919;3642.5082613847057;3876.6898648108368;3885.1064255221177;4152.2733521633745;4153.854615088488;2949.27742703037;3375.869083856911;3610.8194590270646;3630.963690363089;3634.327649384872;4554.681172317939;4692.653673163419;5129.410117836459;5187.884604809984;5301.06511123441;4004.83980640774;4502.16709908668;4748.596369308613;4752.382190315271;4760.714607056076;4961.3798275215595;5704.80033198836;5739.737257803276;5740.56945729072;5759.884802303973;3738.5700644425965;3939.212157568498;3942.526896772377;3949.486566746333;3973.701972352056;5975.312839023524;6015.248093988252;6017.398260173992;6023.6385816851125;6055.061465472859;3594.353016457063;3812.218495208476;4029.49631296088;4043.272211001989;4069.7587235892743;3289.6710328967392;3647.908802279911;3948.466956960831;3967.373376915451;4098.344264917599;4234.3958465543465;4608.953910460919;4630.981184139484;4640.23436133041;4650.558196971282;5607.971960140173;5721.541007043261;5752.964466396089;5784.161296611967;5806.664467172574;1263.2799304396406;1267.467663581298;1306.6276111308232;1332.4470344806991;1357.803118547805;754.1704125461948;767.0948281028328;788.6805843633338;816.8243827577099;831.4263158420671;3757.605451427615;4047.563115094099;4491.54277552775;4492.090861383899;4524.502304746476;4206.31747301082;4684.423567038183;4702.294655801657;4832.661713680009;4876.780544875489;3893.922121557553;4071.6131967463043;4100.733452325649;4495.965927836616;4535.031847133758;2140.68960000799;2232.9170352379083;2409.698762668175;2599.922002339895;2609.6346511488405;4528.773561321921;4598.562761736285;4730.834420795256;5002.698758571025;5058.974705126451;4969.3539839821315;4990.8752281193265;5054.469280725544;5055.949440505621;5099.260607211933;4308.612224899774;4621.445426548768;4707.775978245612;5010.749462526859;5059.696418214919;4171.770552619635;4369.431973843507;4461.933071004002;4823.782929768239;4971.967100069028;4831.492693267156;4841.298011788342;4889.999999999858;4896.804127834989;4916.70499770015;4184.665226781789;4283.87148385549;4343.109662519113;4720.575148236675;5025.346704928336;3146.4021835851113;3329.6503867093606;3405.1952465193485;3410.2667926395943;3418.2821607461715;4338.067315527177;4600.401947746772;4757.3577567028005;4820.373351464287;4967.254911763264;4656.138614356355;5196.855902179127;5549.390676703711;5665.830025099253;5749.7125143741805;4608.631309495166;4849.102915960559;4903.828366007173;5344.194297993585;5716.3701049832825;4558.746344755086;5171.741412929263;5256.868578285421;5565.248691426606;5591.576171114013;4939.9259011114145;5677.403872593318;5699.715014249354;5731.656100633975;5768.913466298093;4681.929771053505;4758.571728544448;5612.130119831432;5612.326520817528;5699.715014249188 09:56:45 INFO - PROCESS | 2245 | |5;yui.html;560.3360018378222;700.1982729774405;756.198429664556;778.2878666020591;784.1296161260999;14007.719845603107;15391.384344626216;15397;16281.75577366339;16318.592035199126;14605.561833145022;14693.853061469383;14720.411183552671;14728.558143255692;14799.186044767534;667.2160211751197;696.1819861662551;838.3628442383787;859.9183077607618;861.5175501719026;819.3035919468464;853.8548446764065;872.2324354567971;877.5129802959354;881.5812489067706;884.2130503851578;924.2882980105314;933.3373304953491;933.9673111441101;935.7847695030147;873.3624454148444;888.706726780161;919.2278486071665;927.2906226736552;929.6235024814927;666.9066330713704;678.8472593666435;679.9218089919624;682.3279070115932;683.2620769568854;14531.055481393782;14533.491327803476;14945.70108597822;15058.924705376512;15119.16844573548;14586.781198282035;14588.854111458855;14696.26518674062;14698;14818.110913345234;11747.882521174764;11928.403579820973;11950.820737688942;11956.70108247292;11996.22024568409;3805.7906815125157;3808.6000969898037;3835.5780864104927;3844.711646626486;3980.661643760284;111778;113277;114981.42509287484;115867.8413215857;116161.41919290434;11217.551297948154;11243.718907027389;11281.830772538418;11482.253653512495;11522.596709115141;23218.535629287657;23477.295681129253;23598.93804778789;23643.40891477727;23647.408814779767;7808.57052862098;7966.322862556747;7970.2826745593175;8035.919640803517;8157.959210203911;3717.4052151656147;4361.149575832682;4373.759443230653;4384.517703052662;4481.574250446202;3504.3166582516674;3595.5325807645363;3617.656322649344;3631.4915911772373;3634.200475895299;636.0237036149489;648.6173157836805;649.8992656138306;652.7128063651978;658.8963486160661;620.512897375558;629.2511910826103;654.2802916791586;675.9019942108366;681.6114814555656;8782.516961567047;9090.500022498825;9334;9569.234461243084;9738.756531086638;498.4317877619517;512.26745753572;542.0564452195998;562.3027445967053;645.8030300758314;4366.432363792686;4446.1330040642415;4654.418197725285;4682.344471773954;4770.61835053195;125417;148425;149991;150708;151823;80577.79133313005;80586;104637.47681261545;124557.37721311336;126125;69299.65350173318;69951.30048699449;70005.64997174981;90535;105604.3598910018;6929.618870962146;7720.382369410321;8455.312040093753;8724.956375218084;8829.2495137914;55760.72119639376;62857.05714414196;63686;64259.35740642534;66345.66827166027;46302.536974629824;60766;64805;76935.2306476928;77200;13090;13101.475940962255;13305.334733263491;13359.599212023655;13493.662658433224;14121.293935302989;14540.563783086523;14709.779353309923;14924.701505969602;15316.770248446059;13413.530526431528;13765.489420762791;13870.583882483541;14178.291085445893;14478.493252736103;12692.36538173106;13803.585892423243;14008.719825603635;14267.928660356632;14289.142651440947;12358.258504489759;13255.403506842022;13501.729965400833;13926.93036534811;13979.021468497107;8206.58967051657;11211.831822522832;11239.381833999209;11429.771404572028;11645.068394528247;8552.230299272846;10439.217058720778;11407.087433005174;11557.537698491966;11572.710682233012 09:56:45 INFO - PROCESS | 2245 | __end_tp_report 09:56:45 INFO - PROCESS | 2245 | __start_cc_report 09:56:45 INFO - PROCESS | 2245 | _x_x_mozilla_cycle_collect,373 09:56:45 INFO - PROCESS | 2245 | __end_cc_report 09:56:45 INFO - PROCESS | 2245 | __startTimestamp1475859405774__endTimestamp 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | ------- Summary: start ------- 09:56:45 INFO - PROCESS | 2245 | Number of tests: 6 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#0] dojo.html Cycles:165 Average:5738.86 Median:5901.68 stddev:641.65 (10.9%) stddev-sans-first:615.52 09:56:45 INFO - PROCESS | 2245 | Values: 3345.1 3367.7 3399.2 3402.8 3410.0 5642.3 5719.1 5735.6 5783.3 5794.0 5661.4 5685.9 5744.8 5782.3 5867.9 5145.9 5166.0 5229.8 5290.2 5297.4 5251.6 5357.5 5392.9 5393.2 5469.7 5193.9 5245.6 5284.3 5294.2 5318.7 5150.4 5207.7 5286.4 5324.8 5341.6 5195.0 5196.2 5205.4 5227.5 5258.9 5572.6 5753.1 5768.7 5778.9 5816.9 5121.0 6138.1 6142.0 6148.7 6224.6 5284.3 6250.3 6266.6 6269.2 6287.8 4380.2 5078.8 5443.2 5890.4 5896.4 6103.5 6190.9 6201.7 6237.4 6382.3 5088.3 5513.3 6126.2 6174.2 6207.4 5847.0 6126.9 6250.4 6274.9 6284.2 6116.9 6136.7 6162.0 6164.2 6245.1 5901.7 6247.0 6255.8 6269.3 6274.0 4975.2 4977.0 4980.4 4980.7 5002.5 4323.6 4412.7 4424.7 4501.7 4542.2 5631.8 5874.1 5874.4 5875.2 5994.1 5528.1 6287.5 6291.6 6293.8 6332.0 5728.6 5859.9 5862.1 5882.2 5893.0 5397.5 5407.8 5428.8 5465.9 5552.5 5855.8 5980.4 6413.4 6441.9 6444.5 6235.7 6244.8 6265.0 6291.1 6321.4 6196.2 6259.2 6293.3 6295.8 6330.6 6217.6 6249.4 6249.5 6252.1 6264.4 6262.1 6267.3 6274.7 6282.3 6320.0 5745.9 6062.8 6102.3 6169.7 6230.1 6007.5 6134.8 6189.9 6191.4 6212.3 5914.9 6008.5 6026.5 6079.3 6089.2 6098.0 6121.2 6125.6 6144.6 6252.3 6072.6 6111.0 6115.8 6126.1 6130.8 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#1] ext.html Cycles:180 Average:67553.82 Median:40029.80 stddev:147727.72 (369.0%) stddev-sans-first:148056.27 09:56:45 INFO - PROCESS | 2245 | Values: 472.2 488.9 499.1 524.7 529.1 33367.0 40382.8 40527.4 40562.8 40596.2 41306.4 41727.8 41785.0 41881.2 41913.8 7595.9 9612.7 9691.2 9852.5 9874.1 8417.4 8709.5 8778.2 9336.3 9563.5 8282.7 9330.2 9652.2 9680.5 9713.5 7794.6 8189.9 9599.4 9656.7 9734.3 8193.5 9584.1 9699.7 9702.5 9717.9 39779.8 39787.4 39942.2 40117.4 40232.0 3303.4 3309.8 3322.6 3557.7 3990.0 30436.4 30745.5 31005.0 31861.0 31951.8 3186.9 3231.1 3243.5 3260.2 3327.4 484642.0 496576.0 501115.0 501138.0 501261.5 54737.7 54902.0 55353.4 55404.4 55466.7 57536.4 57849.2 58274.0 58827.7 58829.0 15077.3 15109.0 15405.2 15426.4 15434.5 11856.6 11894.6 11981.2 11998.0 12001.4 12766.0 13094.5 14700.9 14755.7 14834.1 3120.6 6203.5 6432.5 6462.4 6462.9 817.9 909.8 970.7 977.6 1001.1 28492.0 29292.9 29865.1 29896.9 30044.7 1015.4 1027.3 1087.4 1094.6 1109.6 14920.5 15308.2 15377.5 15421.0 15703.8 557718.0 557760.0 564605.0 564852.0 565608.0 55368.7 55736.4 55891.9 56175.4 56258.7 1315.4 1347.6 1356.4 1359.5 1363.0 24261.5 29081.9 29116.0 29266.4 29846.4 47371.1 47702.3 47765.8 47770.0 47775.8 515989.0 610549.0 612960.0 613706.0 614362.9 20925.2 21864.8 25448.9 25729.9 25779.5 40283.6 42963.0 45547.8 45577.3 46450.0 36088.6 37056.6 37370.0 38351.2 38549.0 30097.0 37220.8 38053.8 38212.6 38230.4 30419.4 34757.1 36327.5 36493.8 37959.0 32395.7 33311.7 33904.0 33904.7 34093.7 19934.0 22052.4 22776.0 24659.9 25275.6 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#2] jquery.html Cycles:180 Average:34843.86 Median:1801.02 stddev:94041.49 (5221.6%) stddev-sans-first:94269.62 09:56:45 INFO - PROCESS | 2245 | Values: 341.2 376.8 376.9 381.5 398.0 16741.9 18425.0 19242.0 20056.7 20881.3 11499.5 11911.8 12198.9 12219.8 12232.4 5017.4 5254.4 5289.9 5392.9 5404.7 3902.6 4103.2 5374.4 5423.9 7391.7 6453.5 7795.2 7837.6 7958.8 7972.4 7674.5 7739.3 7768.6 7803.6 7812.0 7415.9 7424.9 7532.7 7603.9 7603.9 28491.1 28647.7 28763.8 28896.6 29549.0 1952.6 2361.5 2379.8 2388.1 2590.7 16014.8 17266.7 17389.4 17603.8 17719.7 1986.6 2022.8 2029.1 2061.9 2077.9 124651.4 127153.0 130986.0 135888.0 138505.3 20768.5 20971.0 21427.0 21706.6 21885.0 34690.5 35083.3 35167.0 35329.5 35346.1 10194.8 10461.3 10661.7 10797.5 10819.5 8658.8 8731.0 8763.8 8873.0 8885.3 9563.0 9854.5 9921.2 9953.9 9994.7 1528.9 1679.7 1680.4 3260.9 3802.9 717.2 876.8 912.1 921.9 960.0 17086.4 19618.7 20408.8 20986.3 21139.9 792.3 796.5 847.5 934.2 935.7 8657.6 11129.8 11164.8 11188.7 11207.5 450319.0 579804.0 580586.1 596246.0 596979.3 73476.0 83336.0 96386.0 126129.0 130483.0 941.0 970.7 1033.4 1037.5 1040.2 18282.4 18394.4 18659.2 18764.1 19091.0 52483.0 54591.7 54811.0 55314.7 55471.2 74043.0 76165.0 78100.6 79585.0 80426.5 7142.4 7533.8 7775.3 8134.0 8493.3 10069.5 11345.8 12370.2 12802.8 13448.1 11137.2 11451.0 11573.4 11679.1 11701.8 9855.9 10087.4 11094.3 11160.7 11400.0 11477.3 11689.8 11828.8 11891.3 11973.0 8076.2 8282.5 8482.1 8511.9 8629.4 8282.0 10732.9 10849.5 11225.4 11399.8 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#3] mootools.html Cycles:180 Average:5663.25 Median:4990.68 stddev:3227.76 (64.7%) stddev-sans-first:3219.95 09:56:45 INFO - PROCESS | 2245 | Values: 1273.9 1486.4 1598.6 1799.0 1820.6 8036.2 8288.7 8434.3 8883.2 8996.9 8441.1 8742.6 8754.0 8857.5 8877.9 2348.8 2369.8 2386.0 2390.8 2397.8 2348.6 2373.7 2376.5 2381.5 2385.3 2360.0 2366.1 2374.9 2382.1 2386.2 2251.8 2304.3 2315.0 2317.1 2319.4 2314.2 2317.8 2317.9 2323.3 2332.8 8813.0 8882.8 8887.0 8895.9 8902.4 5043.7 5238.7 5241.7 5287.4 5325.9 2876.7 3215.4 3429.2 3588.7 3633.7 1023.4 1048.2 1066.3 1079.5 1081.7 11517.4 11637.7 11653.9 11681.7 11758.5 4566.1 4600.8 4798.0 4818.5 4821.4 9859.0 9919.5 9936.1 9955.8 9958.5 5306.4 5322.6 5338.9 5341.9 5348.0 4407.1 4541.4 4797.4 4841.1 4869.6 2892.6 3320.8 3355.3 3455.5 3701.6 1152.9 1153.2 1249.2 1276.4 1307.2 762.5 845.8 914.7 935.7 950.9 5627.4 5745.3 5851.1 5917.3 5964.9 413.6 416.4 456.0 456.5 458.2 3839.6 3971.6 4020.8 4034.5 4068.7 9940.9 10638.9 10770.9 10784.8 10820.1 10368.3 10751.4 10774.2 10783.6 10830.7 8083.5 8090.2 8180.1 8220.8 8248.3 5685.4 5806.7 5814.6 5834.6 5867.7 9908.7 9958.8 9976.6 9987.3 10026.0 9804.0 9933.1 9972.6 10001.5 10202.1 6859.5 7095.9 7288.7 7453.8 7829.9 8255.0 8364.2 8446.0 8458.9 8483.2 7382.5 7503.5 7526.9 7552.5 7565.7 7266.0 7310.9 7322.9 7384.4 7514.3 6879.2 6897.4 6950.7 6961.7 6978.2 4848.2 4887.3 5001.9 5024.5 5054.5 4938.3 4979.4 5064.5 5125.0 5156.3 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#4] prototype.html Cycles:180 Average:4281.51 Median:4626.21 stddev:1311.06 (28.3%) stddev-sans-first:1280.17 09:56:45 INFO - PROCESS | 2245 | Values: 296.7 302.7 315.0 319.3 320.2 4505.4 4679.1 5202.0 5429.0 5558.7 5403.9 5415.9 5463.0 5472.3 5485.8 4134.5 4173.2 4187.7 4190.4 4195.9 3788.8 4046.6 4151.6 4178.6 4259.6 3383.6 3931.8 4118.9 4120.3 4137.7 3642.5 3876.7 3885.1 4152.3 4153.9 2949.3 3375.9 3610.8 3631.0 3634.3 4554.7 4692.7 5129.4 5187.9 5301.1 4004.8 4502.2 4748.6 4752.4 4760.7 4961.4 5704.8 5739.7 5740.6 5759.9 3738.6 3939.2 3942.5 3949.5 3973.7 5975.3 6015.2 6017.4 6023.6 6055.1 3594.4 3812.2 4029.5 4043.3 4069.8 3289.7 3647.9 3948.5 3967.4 4098.3 4234.4 4609.0 4631.0 4640.2 4650.6 5608.0 5721.5 5753.0 5784.2 5806.7 1263.3 1267.5 1306.6 1332.4 1357.8 754.2 767.1 788.7 816.8 831.4 3757.6 4047.6 4491.5 4492.1 4524.5 4206.3 4684.4 4702.3 4832.7 4876.8 3893.9 4071.6 4100.7 4496.0 4535.0 2140.7 2232.9 2409.7 2599.9 2609.6 4528.8 4598.6 4730.8 5002.7 5059.0 4969.4 4990.9 5054.5 5055.9 5099.3 4308.6 4621.4 4707.8 5010.7 5059.7 4171.8 4369.4 4461.9 4823.8 4972.0 4831.5 4841.3 4890.0 4896.8 4916.7 4184.7 4283.9 4343.1 4720.6 5025.3 3146.4 3329.7 3405.2 3410.3 3418.3 4338.1 4600.4 4757.4 4820.4 4967.3 4656.1 5196.9 5549.4 5665.8 5749.7 4608.6 4849.1 4903.8 5344.2 5716.4 4558.7 5171.7 5256.9 5565.2 5591.6 4939.9 5677.4 5699.7 5731.7 5768.9 4681.9 4758.6 5612.1 5612.3 5699.7 09:56:45 INFO - PROCESS | 2245 | 09:56:45 INFO - PROCESS | 2245 | [#5] yui.html Cycles:180 Average:22705.01 Median:3840.14 stddev:35496.84 (924.4%) stddev-sans-first:35557.48 09:56:45 INFO - PROCESS | 2245 | Values: 560.3 700.2 756.2 778.3 784.1 14007.7 15391.4 15397.0 16281.8 16318.6 14605.6 14693.9 14720.4 14728.6 14799.2 667.2 696.2 838.4 859.9 861.5 819.3 853.9 872.2 877.5 881.6 884.2 924.3 933.3 934.0 935.8 873.4 888.7 919.2 927.3 929.6 666.9 678.8 679.9 682.3 683.3 14531.1 14533.5 14945.7 15058.9 15119.2 14586.8 14588.9 14696.3 14698.0 14818.1 11747.9 11928.4 11950.8 11956.7 11996.2 3805.8 3808.6 3835.6 3844.7 3980.7 111778.0 113277.0 114981.4 115867.8 116161.4 11217.6 11243.7 11281.8 11482.3 11522.6 23218.5 23477.3 23598.9 23643.4 23647.4 7808.6 7966.3 7970.3 8035.9 8158.0 3717.4 4361.1 4373.8 4384.5 4481.6 3504.3 3595.5 3617.7 3631.5 3634.2 636.0 648.6 649.9 652.7 658.9 620.5 629.3 654.3 675.9 681.6 8782.5 9090.5 9334.0 9569.2 9738.8 498.4 512.3 542.1 562.3 645.8 4366.4 4446.1 4654.4 4682.3 4770.6 125417.0 148425.0 149991.0 150708.0 151823.0 80577.8 80586.0 104637.5 124557.4 126125.0 69299.7 69951.3 70005.6 90535.0 105604.4 6929.6 7720.4 8455.3 8725.0 8829.2 55760.7 62857.1 63686.0 64259.4 66345.7 46302.5 60766.0 64805.0 76935.2 77200.0 13090.0 13101.5 13305.3 13359.6 13493.7 14121.3 14540.6 14709.8 14924.7 15316.8 13413.5 13765.5 13870.6 14178.3 14478.5 12692.4 13803.6 14008.7 14267.9 14289.1 12358.3 13255.4 13501.7 13926.9 13979.0 8206.6 11211.8 11239.4 11429.8 11645.1 8552.2 10439.2 11407.1 11557.5 11572.7 09:56:45 INFO - PROCESS | 2245 | -------- Summary: end -------- 09:56:45 INFO - PROCESS | 2245 | 09:56:46 INFO - TEST-INFO | 2245: exit 0 09:56:46 INFO - TEST-OK | dromaeo_css | took 1115446ms 09:56:46 INFO - TEST-START | kraken 09:56:46 INFO - operating with platform_type : mac_ 09:56:46 INFO - Initialising browser for kraken test... 09:56:46 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpML8G8p/profile http://localhost:52413/getInfo.html) 09:56:49 INFO - PROCESS | 2249 | 2016-10-07 09:56:49.093 plugin-container[2250:19267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.tsm.portname' 09:56:49 INFO - PROCESS | 2249 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:49 INFO - PROCESS | 2249 | 2016-10-07 09:56:49.096 plugin-container[2250:19267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 09:56:49 INFO - PROCESS | 2249 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:49 INFO - PROCESS | 2249 | 2016-10-07 09:56:49.096 plugin-container[2250:19267] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:49 INFO - PROCESS | 2249 | __metrics Screen width/height:1600/1200 09:56:49 INFO - PROCESS | 2249 | colorDepth:24 09:56:49 INFO - PROCESS | 2249 | Browser inner width/height: 1024/689 09:56:49 INFO - PROCESS | 2249 | __metrics 09:56:49 ERROR - PROCESS | 2249 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 09:56:49 INFO - TEST-INFO | 2249: exit 0 09:56:49 INFO - Browser initialized. 09:56:49 INFO - Running cycle 1/1 for kraken test... 09:56:49 INFO - TEST-INFO | started process 2251 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpML8G8p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:52 INFO - PROCESS | 2251 | 2016-10-07 09:56:52.368 plugin-container[2252:19382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1f, name = 'com.apple.tsm.portname' 09:56:52 INFO - PROCESS | 2251 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:52 INFO - PROCESS | 2251 | 2016-10-07 09:56:52.370 plugin-container[2252:19382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 09:56:52 INFO - PROCESS | 2251 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:52 INFO - PROCESS | 2251 | 2016-10-07 09:56:52.370 plugin-container[2252:19382] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:01 INFO - PROCESS | 2251 | RSS: Main: 239837184 09:57:01 INFO - PROCESS | 2251 | 09:57:04 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52413/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:05 INFO - PROCESS | 2251 | RSS: Main: 253427712 09:57:05 INFO - PROCESS | 2251 | 09:57:08 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52413/tests/kraken/driver.html?testName=audio-dft) 09:57:09 INFO - PROCESS | 2251 | RSS: Main: 261783552 09:57:09 INFO - PROCESS | 2251 | 09:57:12 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52413/tests/kraken/driver.html?testName=audio-fft) 09:57:13 INFO - PROCESS | 2251 | RSS: Main: 262127616 09:57:13 INFO - PROCESS | 2251 | 09:57:14 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52413/tests/kraken/driver.html?testName=audio-oscillator) 09:57:15 INFO - PROCESS | 2251 | RSS: Main: 264425472 09:57:15 INFO - PROCESS | 2251 | 09:57:17 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52413/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:18 INFO - PROCESS | 2251 | RSS: Main: 264630272 09:57:18 INFO - PROCESS | 2251 | 09:57:20 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52413/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:21 INFO - PROCESS | 2251 | RSS: Main: 265400320 09:57:21 INFO - PROCESS | 2251 | 09:57:24 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52413/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:25 INFO - PROCESS | 2251 | RSS: Main: 265293824 09:57:25 INFO - PROCESS | 2251 | 09:57:27 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52413/tests/kraken/driver.html?testName=json-parse-financial) 09:57:28 INFO - PROCESS | 2251 | RSS: Main: 265777152 09:57:28 INFO - PROCESS | 2251 | 09:57:30 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52413/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:30 INFO - PROCESS | 2251 | RSS: Main: 265388032 09:57:30 INFO - PROCESS | 2251 | 09:57:32 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:33 INFO - PROCESS | 2251 | RSS: Main: 265515008 09:57:33 INFO - PROCESS | 2251 | 09:57:35 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:36 INFO - PROCESS | 2251 | RSS: Main: 265510912 09:57:36 INFO - PROCESS | 2251 | 09:57:39 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:39 INFO - PROCESS | 2251 | RSS: Main: 265482240 09:57:39 INFO - PROCESS | 2251 | 09:57:42 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:43 INFO - PROCESS | 2251 | RSS: Main: 265826304 09:57:43 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:52413/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52413/tests/kraken/driver.html?testName=ai-astar) 09:57:45 INFO - PROCESS | 2251 | RSS: Main: 267505664 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | __start_tp_report 09:57:45 INFO - PROCESS | 2251 | _x_x_mozilla_page_load 09:57:45 INFO - PROCESS | 2251 | _x_x_mozilla_page_load_details 09:57:45 INFO - PROCESS | 2251 | |i|pagename|runs| 09:57:45 INFO - PROCESS | 2251 | |0;ai-astar;116;118;118;133;116;110;111;118;117;118 09:57:45 INFO - PROCESS | 2251 | |1;audio-beat-detection;148;115;112;153;115;117;118;131;149;140 09:57:45 INFO - PROCESS | 2251 | |2;audio-dft;194;214;225;197;197;197;197;228;260;195 09:57:45 INFO - PROCESS | 2251 | |3;audio-fft;82;58;60;87;59;59;63;60;103;82 09:57:45 INFO - PROCESS | 2251 | |4;audio-oscillator;87;70;87;72;70;71;92;68;72;87 09:57:45 INFO - PROCESS | 2251 | |5;imaging-gaussian-blur;103;86;89;86;90;86;89;89;89;86 09:57:45 INFO - PROCESS | 2251 | |6;imaging-darkroom;98;99;98;103;99;112;172;100;98;100 09:57:45 INFO - PROCESS | 2251 | |7;imaging-desaturate;129;93;89;91;99;91;91;90;91;92 09:57:45 INFO - PROCESS | 2251 | |8;json-parse-financial;57;55;55;56;57;58;58;60;75;75 09:57:45 INFO - PROCESS | 2251 | |9;json-stringify-tinderbox;74;76;71;84;80;76;69;70;70;73 09:57:45 INFO - PROCESS | 2251 | |10;stanford-crypto-aes;87;91;106;77;97;79;98;78;87;104 09:57:45 INFO - PROCESS | 2251 | |11;stanford-crypto-ccm;149;139;162;161;137;167;171;160;199;162 09:57:45 INFO - PROCESS | 2251 | |12;stanford-crypto-pbkdf2;173;177;172;174;177;173;175;194;223;173 09:57:45 INFO - PROCESS | 2251 | |13;stanford-crypto-sha256-iterative;70;68;97;89;93;75;69;68;69;71 09:57:45 INFO - PROCESS | 2251 | __end_tp_report 09:57:45 INFO - PROCESS | 2251 | __start_cc_report 09:57:45 INFO - PROCESS | 2251 | _x_x_mozilla_cycle_collect,931 09:57:45 INFO - PROCESS | 2251 | __end_cc_report 09:57:45 INFO - PROCESS | 2251 | __startTimestamp1475859465536__endTimestamp 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | ------- Summary: start ------- 09:57:45 INFO - PROCESS | 2251 | Number of tests: 14 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#0] ai-astar Cycles:10 Average:117.50 Median:118.00 stddev:6.19 (5.2%) stddev-sans-first:6.54 09:57:45 INFO - PROCESS | 2251 | Values: 116.0 118.0 118.0 133.0 116.0 110.0 111.0 118.0 117.0 118.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:135.50 stddev:16.34 (12.1%) stddev-sans-first:15.94 09:57:45 INFO - PROCESS | 2251 | Values: 148.0 115.0 112.0 153.0 115.0 117.0 118.0 131.0 149.0 140.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#2] audio-dft Cycles:10 Average:210.40 Median:205.50 stddev:21.65 (10.5%) stddev-sans-first:22.14 09:57:45 INFO - PROCESS | 2251 | Values: 194.0 214.0 225.0 197.0 197.0 197.0 197.0 228.0 260.0 195.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#3] audio-fft Cycles:10 Average:71.30 Median:61.50 stddev:15.93 (25.9%) stddev-sans-first:16.42 09:57:45 INFO - PROCESS | 2251 | Values: 82.0 58.0 60.0 87.0 59.0 59.0 63.0 60.0 103.0 82.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:9.35 (11.8%) stddev-sans-first:9.28 09:57:45 INFO - PROCESS | 2251 | Values: 87.0 70.0 87.0 72.0 70.0 71.0 92.0 68.0 72.0 87.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:89.00 stddev:5.08 (5.7%) stddev-sans-first:1.72 09:57:45 INFO - PROCESS | 2251 | Values: 103.0 86.0 89.0 86.0 90.0 86.0 89.0 89.0 89.0 86.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:98.00 stddev:22.92 (23.4%) stddev-sans-first:24.03 09:57:45 INFO - PROCESS | 2251 | Values: 98.0 99.0 98.0 103.0 99.0 112.0 172.0 100.0 98.0 100.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:91.00 stddev:12.05 (13.2%) stddev-sans-first:2.89 09:57:45 INFO - PROCESS | 2251 | Values: 129.0 93.0 89.0 91.0 99.0 91.0 91.0 90.0 91.0 92.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:58.00 stddev:7.73 (13.3%) stddev-sans-first:8.09 09:57:45 INFO - PROCESS | 2251 | Values: 57.0 55.0 55.0 56.0 57.0 58.0 58.0 60.0 75.0 75.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:75.00 stddev:4.83 (6.4%) stddev-sans-first:5.12 09:57:45 INFO - PROCESS | 2251 | Values: 74.0 76.0 71.0 84.0 80.0 76.0 69.0 70.0 70.0 73.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:87.00 stddev:10.63 (12.2%) stddev-sans-first:11.20 09:57:45 INFO - PROCESS | 2251 | Values: 87.0 91.0 106.0 77.0 97.0 79.0 98.0 78.0 87.0 104.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#11] stanford-crypto-ccm Cycles:10 Average:160.70 Median:162.00 stddev:17.59 (10.9%) stddev-sans-first:18.15 09:57:45 INFO - PROCESS | 2251 | Values: 149.0 139.0 162.0 161.0 137.0 167.0 171.0 160.0 199.0 162.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:176.00 stddev:16.07 (9.1%) stddev-sans-first:16.77 09:57:45 INFO - PROCESS | 2251 | Values: 173.0 177.0 172.0 174.0 177.0 173.0 175.0 194.0 223.0 173.0 09:57:45 INFO - PROCESS | 2251 | 09:57:45 INFO - PROCESS | 2251 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:73.00 stddev:11.45 (15.7%) stddev-sans-first:11.86 09:57:45 INFO - PROCESS | 2251 | Values: 70.0 68.0 97.0 89.0 93.0 75.0 69.0 68.0 69.0 71.0 09:57:45 INFO - PROCESS | 2251 | -------- Summary: end -------- 09:57:45 INFO - PROCESS | 2251 | 09:57:46 INFO - TEST-INFO | 2251: exit 0 09:57:46 INFO - TEST-OK | kraken | took 59961ms 09:57:46 INFO - SUITE-END | took 1175s 09:57:46 INFO - Completed test suite (00:19:35) 09:57:46 INFO - javascript benchmark 09:57:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7275.351889086932, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.0633822529685, 3367.6969072783454, 3399.1672040350118, 3402.7958322500635, 3410.0281419795365, 5642.322921249456, 5719.056355701309, 5735.627184233027, 5783.306003279602, 5793.971030144849, 5661.433856614337, 5685.943140568593, 5744.827655170341, 5782.335031471382, 5867.88264234715, 5145.948540514603, 5166.018456493273, 5229.764660590283, 5290.206469029637, 5297.417284098746, 5251.55361794248, 5357.464253574631, 5392.946070539284, 5393.2449457076045, 5469.72651367432, 5193.948060519384, 5245.606579506514, 5284.2866213061125, 5294.232336311252, 5318.680879147244, 5150.407703114152, 5207.739613019372, 5286.418493965661, 5324.840254792362, 5341.599380046512, 5194.974025129888, 5196.194589838581, 5205.401378841444, 5227.45111763267, 5258.947410525922, 5572.58205634575, 5753.137029445575, 5768.740406681667, 5778.913316300259, 5816.854578635525, 5120.974395128038, 6138.1099740537375, 6141.969290153565, 6148.692565371758, 6224.62652240867, 5284.339457567804, 6250.281217659982, 6266.624002559815, 6269.185005949197, 6287.842803929892, 4380.2115619188535, 5078.806480477085, 5443.237946687467, 5890.410958904075, 5896.439838215363, 6103.549811064039, 6190.876182476325, 6201.6899155042065, 6237.438630523184, 6382.32985536512, 5088.338515992971, 5513.255710479034, 6126.172966649535, 6174.197354343906, 6207.379262073757, 5847.0, 6126.877462450726, 6250.4374606285655, 6274.905876411857, 6284.214473190851, 6116.883633902656, 6136.723847426876, 6161.952468080437, 6164.198654174928, 6245.094461303176, 5901.675407852609, 6246.968765156236, 6255.812325630239, 6269.310375858652, 6273.96863015682, 4975.179095449211, 4977.0, 4980.402351717817, 4980.651354405157, 5002.474740152305, 4323.610875021263, 4412.7352358857925, 4424.6681498887065, 4501.68488205829, 4542.227821270392, 5631.831045068655, 5874.118882167709, 5874.412558744091, 5875.177475153397, 5994.130851026577, 5528.115501519818, 6287.49700023997, 6291.559590828598, 6293.811185664437, 6332.0, 5728.570357223225, 5859.882802343929, 5862.091375836752, 5882.176495290662, 5893.0, 5397.541208997201, 5407.83776486706, 5428.75570599324, 5465.863353416196, 5552.52803511706, 5855.770288239432, 5980.43185897339, 6413.35866413355, 6441.871162576722, 6444.516661250331, 6235.657038862906, 6244.843878903063, 6264.968675156595, 6291.056341548804, 6321.367863213642, 6196.1635179252025, 6259.155014073224, 6293.3391993841815, 6295.811125666238, 6330.556861019684, 6217.595856269328, 6249.375062493714, 6249.468795152463, 6252.09344645033, 6264.404881536247, 6262.060690896402, 6267.2792628848265, 6274.717637706405, 6282.340354262737, 6319.968400158154, 5745.91381129275, 6062.818115456544, 6102.337773232528, 6169.722362493789, 6230.096635987847, 6007.48936340416, 6134.8159555213415, 6189.938100618936, 6191.4118158774845, 6212.285587157535, 5914.852128696817, 6008.459238668541, 6026.548008899263, 6079.300880398634, 6089.238845144357, 6098.0, 6121.178620693132, 6125.601835880653, 6144.556029333014, 6252.312245652884, 6072.575162879847, 6110.969445152746, 6115.816525504242, 6126.050462178369, 6130.846728831815], "value": 5699.859907142594, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.19019381760273, 488.8655619704583, 499.1140725212747, 524.685188886668, 529.0767610519642, 33366.998990030304, 40382.7980860096, 40527.39208911861, 40562.79718601407, 40596.18807623846, 41306.38040429389, 41727.79136104315, 41785.0, 41881.16237675244, 41913.79043104788, 7595.886061709079, 9612.663556775515, 9691.224702023821, 9852.50737463124, 9874.061964113434, 8417.368697347692, 8709.520976346299, 8778.2099611035, 9336.3464557481, 9563.521823908777, 8282.668693252263, 9330.160285574297, 9652.179564737005, 9680.515974201297, 9713.514324283793, 7794.610269486503, 8189.8771518427275, 9599.42403455795, 9656.662016829447, 9734.318597698093, 8193.50838949665, 9584.089511496397, 9699.70900872975, 9702.51487425626, 9717.90282097177, 39779.80110099431, 39787.40318895219, 39942.20115597672, 40117.398239026435, 40232.0, 3303.3888730584677, 3309.7683162178655, 3322.5680661513848, 3557.679808817193, 3989.960100398988, 30436.391272174653, 30745.538816917764, 31005.0, 31861.044168674976, 31951.840240798647, 3186.8725250990165, 3231.127595549212, 3243.464828303327, 3260.1849537615594, 3327.4010678077934, 484642.0, 496576.0, 501115.0, 501138.0, 501261.4936925328, 54737.726311368984, 54902.0, 55353.446465535635, 55404.44595553993, 55466.72266638641, 57536.42463575311, 57849.1903593018, 58274.0, 58827.70586147042, 58829.0, 15077.321520531603, 15109.0, 15405.22973851303, 15426.382944682087, 15434.45979390716, 11856.585019524275, 11894.583689570823, 11981.161318707607, 11998.0, 12001.39993000364, 12765.978721702242, 13094.541691040768, 14700.852991470163, 14755.704885902222, 14834.10995340283, 3120.563121163039, 6203.534734894901, 6432.51756118293, 6462.386073323027, 6462.903056454157, 817.9304759095425, 909.7718080591148, 970.7039352997404, 977.6480467031863, 1001.104011909337, 28492.0, 29292.853535732185, 29865.145383133655, 29896.850515747283, 30044.699553004626, 1015.4465816130079, 1027.342500799474, 1087.4073629871739, 1094.6442406218011, 1109.606089838112, 14920.477783277533, 15308.158051307286, 15377.538673839803, 15421.0, 15703.842961570466, 557718.0, 557760.0, 564605.0, 564852.0, 565608.0, 55368.72315638396, 55736.442635573934, 55891.88216235652, 56175.43824561702, 56258.718706407024, 1315.4343632238229, 1347.6226656536185, 1356.4302992743071, 1359.4698067753782, 1362.9931850340686, 24261.51476970486, 29081.85459072691, 29116.0, 29266.41467170602, 29846.403071938006, 47371.05257894892, 47702.284465733734, 47765.761171193924, 47770.0, 47775.76112119417, 515989.0, 610549.0, 612960.0, 613706.0, 614362.8563714306, 20925.16299348009, 21864.78135218691, 25448.872755636843, 25729.871350643127, 25779.484410311314, 40283.597164029154, 42963.0, 45547.77226113848, 45577.316340254256, 46450.0, 36088.63911360958, 37056.629433706396, 37370.0, 38351.23297533978, 38549.0, 30097.0, 37220.81389593035, 38053.80973095228, 38212.617873820906, 38230.42654360242, 30419.39161216719, 34757.13107172341, 36327.45508817317, 36493.817530913235, 37959.0, 32395.676043239266, 33311.666883331825, 33904.0, 33904.66095339015, 34093.65906341004, 19934.0, 22052.448688782908, 22775.97508112172, 24659.876700616383, 25275.620865687397], "value": 18997.931450226228, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.1539382331817, 376.8454933477274, 376.86244520749915, 381.47547122706277, 397.96219359160887, 16741.91629041856, 18425.0, 19242.0, 20056.69914951275, 20881.269155579575, 11499.482523286451, 11911.7617647647, 12198.939005304961, 12219.816702749444, 12232.449539770709, 5017.448080711119, 5254.369475662926, 5289.920651190235, 5392.946070539303, 5404.729763511828, 3902.570717221103, 4103.158852435258, 5374.435684253156, 5423.864403389907, 7391.667374968112, 6453.451456626192, 7795.18150594188, 7837.647305871249, 7958.801029974239, 7972.402069844785, 7674.501157424777, 7739.264769846855, 7768.601651702691, 7803.648835802374, 7812.023497062867, 7415.925840741577, 7424.8856386030175, 7532.698692052366, 7603.885941710879, 7603.885941710879, 28491.145265642062, 28647.71352286492, 28763.849446022134, 28896.566551501743, 29548.96578619738, 1952.6387618290662, 2361.5158892427185, 2379.8096152307776, 2388.0686532252435, 2590.650262214596, 16014.839851601451, 17266.65466690659, 17389.39137130207, 17603.823961760474, 17719.73420398695, 1986.6424043672134, 2022.8280596149345, 2029.1173339597322, 2061.8556701030784, 2077.9168833246804, 124651.3767431157, 127153.0, 130986.0, 135888.0, 138505.307473463, 20768.48078798042, 20971.0, 21427.035783389783, 21706.565868682854, 21885.0, 34690.47964280538, 35083.29833403318, 35167.0, 35329.470057949155, 35346.05596692207, 10194.809359136823, 10461.26771126029, 10661.680149595524, 10797.514111864984, 10819.459027048773, 8658.826823463496, 8731.0, 8763.824723505495, 8873.0, 8885.333599979898, 9563.0, 9854.50727463624, 9921.206303495703, 9953.900460995443, 9994.650187243557, 1528.9311980960883, 1679.673058283942, 1680.411855850467, 3260.8858689945737, 3802.9429558556644, 717.2397258905577, 876.8465518534231, 912.0605776050678, 921.9489781648925, 959.9760005999766, 17086.40197593078, 19618.70571941422, 20408.79591204069, 20986.2654807081, 21139.943002849795, 792.2988155482325, 796.4703472190928, 847.4872702015315, 934.1545900959643, 935.7239614313794, 8657.567121643893, 11129.777404451866, 11164.776704466027, 11188.66434006981, 11207.495662695199, 450319.0, 579804.0, 580586.0970695204, 596246.0, 596979.3020697982, 73476.0, 83336.0, 96386.0, 126129.0, 130483.0, 940.9952950235205, 970.679675707015, 1033.426448321189, 1037.4501514197486, 1040.15747244732, 18282.360117395827, 18394.448166555027, 18659.160337785102, 18764.061796909828, 19091.0, 52483.0, 54591.72704136454, 54811.0, 55314.72342638261, 55471.16793248024, 74043.0, 76165.0, 78100.60949695215, 79585.0, 80426.54454920822, 7142.392896603847, 7533.7739867801865, 7775.300222979959, 8133.959330203311, 8493.320534357114, 10069.546870390997, 11345.773084538097, 12370.195937264049, 12802.84774370311, 13448.1258718183, 11137.220394572301, 11451.0, 11573.363465009505, 11679.124065694938, 11701.824472632747, 9855.901440985499, 10087.445190514592, 11094.334339939629, 11160.720981975515, 11399.97400233983, 11477.311361318347, 11689.824652630388, 11828.76342473163, 11891.345975971404, 11973.0, 8076.152004039492, 8282.50304981703, 8482.109378515168, 8511.914880851113, 8629.35279854026, 8282.0, 10732.892671073188, 10849.45752712377, 11225.438728063727, 11399.829002565135], "value": 10439.383987236628, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.9235346132518, 1486.383150992338, 1598.6163320803012, 1798.9730154047684, 1820.6085691576316, 8036.236557527026, 8288.70989515367, 8434.283085937695, 8883.244924181445, 8996.91003089969, 8441.07148213696, 8742.562871856398, 8753.95623021884, 8857.468551886892, 8877.866831997526, 2348.8120950323932, 2369.7748713872197, 2386.033656369173, 2390.760923907613, 2397.7841994220516, 2348.6007378745653, 2373.738888722248, 2376.514678326046, 2381.4760752634393, 2385.2844146755992, 2360.0, 2366.148186652812, 2374.8931298091543, 2382.0709923129994, 2386.2483317754936, 2251.8423710340285, 2304.3087073877855, 2314.9884250578807, 2317.096332430362, 2319.350581837088, 2314.236302020346, 2317.7566355532763, 2317.9420514487106, 2323.314622186442, 2332.8017118544944, 8812.955935220347, 8882.822343553093, 8887.0, 8895.911040889638, 8902.42134261271, 5043.747812609392, 5238.685678859242, 5241.73791310433, 5287.444818294062, 5325.946740532584, 2876.6548014238215, 3215.389076075538, 3429.176997520602, 3588.7129029677826, 3633.6911362534215, 1023.3961962442121, 1048.2138396202847, 1066.306900514664, 1079.525008996047, 1081.6754973507916, 11517.36654484003, 11637.709057273636, 11653.88346116545, 11681.70795730105, 11758.470868810924, 4566.06395688883, 4600.815967361268, 4797.976010119997, 4818.494058123917, 4821.421429428491, 9859.0, 9919.454430006275, 9936.105750482348, 9955.751106222402, 9958.452285124387, 5306.363236411578, 5322.600804939568, 5338.91991620126, 5341.946580534145, 5347.973260133675, 4407.096545208289, 4541.409616749801, 4797.4482934462285, 4841.104395686809, 4869.610431165498, 2892.5661150827123, 3320.7841490303053, 3355.345707587046, 3455.5486695587915, 3701.648898152209, 1152.9192956492966, 1153.2273376837538, 1249.181785930217, 1276.3809552367095, 1307.2026064100892, 762.523422860712, 845.7843249971218, 914.6981496106268, 935.7239614313794, 950.8573713942964, 5627.43725627434, 5745.3105627324985, 5851.122331650201, 5917.289925208916, 5964.910526342108, 413.57195302861993, 416.4024624663617, 456.0081822037056, 456.4727739460928, 458.15241802664804, 3839.577646458887, 3971.5829837867186, 4020.7788571628257, 4034.495688038995, 4068.6541643960004, 9940.850887236697, 10638.94680526608, 10770.946145269223, 10784.838227426595, 10820.13438924884, 10368.326058806153, 10751.408672523086, 10774.19193560486, 10783.6225732099, 10830.67507974762, 8083.514989100555, 8090.231428014329, 8180.14108518597, 8220.753377398687, 8248.340132789363, 5685.431456854447, 5806.65160090396, 5814.622049566765, 5834.649921004753, 5867.735951882261, 9908.702738917844, 9958.800823983625, 9976.60093596248, 9987.300888937705, 10026.0, 9804.0, 9933.056359645821, 9972.60109595637, 10001.499925003865, 10202.081812636898, 6859.519833611599, 7095.929040709733, 7288.67200975968, 7453.813654658677, 7829.882551761614, 8255.0, 8364.247217750431, 8445.95777021111, 8458.915410845813, 8483.151684831468, 7382.52013619113, 7503.512271702322, 7526.924730752841, 7552.509086909334, 7565.659545320364, 7266.0, 7310.926890731024, 7322.926770732438, 7384.37232835215, 7514.323710866049, 6879.208890977401, 6897.448204143757, 6950.687219075053, 6961.721531138698, 6978.197507286727, 4848.151573474698, 4887.266909963534, 5001.924971125363, 5024.522670346311, 5054.519820617035, 4938.283948827472, 4979.427365852828, 5064.468230835857, 5125.000624878113, 5156.2523434102595], "value": 4452.604800677045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.68551335584283, 302.6519502572042, 314.9984250078749, 319.2529481014426, 320.2154720933713, 4505.43682039745, 4679.1109689159075, 5202.0, 5428.972855135724, 5558.749856256468, 5403.918941215885, 5415.9458405415935, 5463.016657001738, 5472.34331880174, 5485.835424937259, 4134.498628329862, 4173.165366926627, 4187.74873507589, 4190.434291370657, 4195.874123776276, 3788.810559472029, 4046.615571520701, 4151.584841515854, 4178.56125106864, 4259.552746961555, 3383.5601371821754, 3931.842726290945, 4118.938215926763, 4120.278951183546, 4137.731047481919, 3642.5082613847057, 3876.6898648108368, 3885.1064255221177, 4152.2733521633745, 4153.854615088488, 2949.27742703037, 3375.869083856911, 3610.8194590270646, 3630.963690363089, 3634.327649384872, 4554.681172317939, 4692.653673163419, 5129.410117836459, 5187.884604809984, 5301.06511123441, 4004.83980640774, 4502.16709908668, 4748.596369308613, 4752.382190315271, 4760.714607056076, 4961.3798275215595, 5704.80033198836, 5739.737257803276, 5740.56945729072, 5759.884802303973, 3738.5700644425965, 3939.212157568498, 3942.526896772377, 3949.486566746333, 3973.701972352056, 5975.312839023524, 6015.248093988252, 6017.398260173992, 6023.6385816851125, 6055.061465472859, 3594.353016457063, 3812.218495208476, 4029.49631296088, 4043.272211001989, 4069.7587235892743, 3289.6710328967392, 3647.908802279911, 3948.466956960831, 3967.373376915451, 4098.344264917599, 4234.3958465543465, 4608.953910460919, 4630.981184139484, 4640.23436133041, 4650.558196971282, 5607.971960140173, 5721.541007043261, 5752.964466396089, 5784.161296611967, 5806.664467172574, 1263.2799304396406, 1267.467663581298, 1306.6276111308232, 1332.4470344806991, 1357.803118547805, 754.1704125461948, 767.0948281028328, 788.6805843633338, 816.8243827577099, 831.4263158420671, 3757.605451427615, 4047.563115094099, 4491.54277552775, 4492.090861383899, 4524.502304746476, 4206.31747301082, 4684.423567038183, 4702.294655801657, 4832.661713680009, 4876.780544875489, 3893.922121557553, 4071.6131967463043, 4100.733452325649, 4495.965927836616, 4535.031847133758, 2140.68960000799, 2232.9170352379083, 2409.698762668175, 2599.922002339895, 2609.6346511488405, 4528.773561321921, 4598.562761736285, 4730.834420795256, 5002.698758571025, 5058.974705126451, 4969.3539839821315, 4990.8752281193265, 5054.469280725544, 5055.949440505621, 5099.260607211933, 4308.612224899774, 4621.445426548768, 4707.775978245612, 5010.749462526859, 5059.696418214919, 4171.770552619635, 4369.431973843507, 4461.933071004002, 4823.782929768239, 4971.967100069028, 4831.492693267156, 4841.298011788342, 4889.999999999858, 4896.804127834989, 4916.70499770015, 4184.665226781789, 4283.87148385549, 4343.109662519113, 4720.575148236675, 5025.346704928336, 3146.4021835851113, 3329.6503867093606, 3405.1952465193485, 3410.2667926395943, 3418.2821607461715, 4338.067315527177, 4600.401947746772, 4757.3577567028005, 4820.373351464287, 4967.254911763264, 4656.138614356355, 5196.855902179127, 5549.390676703711, 5665.830025099253, 5749.7125143741805, 4608.631309495166, 4849.102915960559, 4903.828366007173, 5344.194297993585, 5716.3701049832825, 4558.746344755086, 5171.741412929263, 5256.868578285421, 5565.248691426606, 5591.576171114013, 4939.9259011114145, 5677.403872593318, 5699.715014249354, 5731.656100633975, 5768.913466298093, 4681.929771053505, 4758.571728544448, 5612.130119831432, 5612.326520817528, 5699.715014249188], "value": 3868.079656650671, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.3360018378222, 700.1982729774405, 756.198429664556, 778.2878666020591, 784.1296161260999, 14007.719845603107, 15391.384344626216, 15397.0, 16281.75577366339, 16318.592035199126, 14605.561833145022, 14693.853061469383, 14720.411183552671, 14728.558143255692, 14799.186044767534, 667.2160211751197, 696.1819861662551, 838.3628442383787, 859.9183077607618, 861.5175501719026, 819.3035919468464, 853.8548446764065, 872.2324354567971, 877.5129802959354, 881.5812489067706, 884.2130503851578, 924.2882980105314, 933.3373304953491, 933.9673111441101, 935.7847695030147, 873.3624454148444, 888.706726780161, 919.2278486071665, 927.2906226736552, 929.6235024814927, 666.9066330713704, 678.8472593666435, 679.9218089919624, 682.3279070115932, 683.2620769568854, 14531.055481393782, 14533.491327803476, 14945.70108597822, 15058.924705376512, 15119.16844573548, 14586.781198282035, 14588.854111458855, 14696.26518674062, 14698.0, 14818.110913345234, 11747.882521174764, 11928.403579820973, 11950.820737688942, 11956.70108247292, 11996.22024568409, 3805.7906815125157, 3808.6000969898037, 3835.5780864104927, 3844.711646626486, 3980.661643760284, 111778.0, 113277.0, 114981.42509287484, 115867.8413215857, 116161.41919290434, 11217.551297948154, 11243.718907027389, 11281.830772538418, 11482.253653512495, 11522.596709115141, 23218.535629287657, 23477.295681129253, 23598.93804778789, 23643.40891477727, 23647.408814779767, 7808.57052862098, 7966.322862556747, 7970.2826745593175, 8035.919640803517, 8157.959210203911, 3717.4052151656147, 4361.149575832682, 4373.759443230653, 4384.517703052662, 4481.574250446202, 3504.3166582516674, 3595.5325807645363, 3617.656322649344, 3631.4915911772373, 3634.200475895299, 636.0237036149489, 648.6173157836805, 649.8992656138306, 652.7128063651978, 658.8963486160661, 620.512897375558, 629.2511910826103, 654.2802916791586, 675.9019942108366, 681.6114814555656, 8782.516961567047, 9090.500022498825, 9334.0, 9569.234461243084, 9738.756531086638, 498.4317877619517, 512.26745753572, 542.0564452195998, 562.3027445967053, 645.8030300758314, 4366.432363792686, 4446.1330040642415, 4654.418197725285, 4682.344471773954, 4770.61835053195, 125417.0, 148425.0, 149991.0, 150708.0, 151823.0, 80577.79133313005, 80586.0, 104637.47681261545, 124557.37721311336, 126125.0, 69299.65350173318, 69951.30048699449, 70005.64997174981, 90535.0, 105604.3598910018, 6929.618870962146, 7720.382369410321, 8455.312040093753, 8724.956375218084, 8829.2495137914, 55760.72119639376, 62857.05714414196, 63686.0, 64259.35740642534, 66345.66827166027, 46302.536974629824, 60766.0, 64805.0, 76935.2306476928, 77200.0, 13090.0, 13101.475940962255, 13305.334733263491, 13359.599212023655, 13493.662658433224, 14121.293935302989, 14540.563783086523, 14709.779353309923, 14924.701505969602, 15316.770248446059, 13413.530526431528, 13765.489420762791, 13870.583882483541, 14178.291085445893, 14478.493252736103, 12692.36538173106, 13803.585892423243, 14008.719825603635, 14267.928660356632, 14289.142651440947, 12358.258504489759, 13255.403506842022, 13501.729965400833, 13926.93036534811, 13979.021468497107, 8206.58967051657, 11211.831822522832, 11239.381833999209, 11429.771404572028, 11645.068394528247, 8552.230299272846, 10439.217058720778, 11407.087433005174, 11557.537698491966, 11572.710682233012], "value": 7615.8744655132, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 118.0, 133.0, 116.0, 110.0, 111.0, 118.0, 117.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 112.0, 153.0, 115.0, 117.0, 118.0, 131.0, 149.0, 140.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 214.0, 225.0, 197.0, 197.0, 197.0, 197.0, 228.0, 260.0, 195.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 87.0, 59.0, 59.0, 63.0, 60.0, 103.0, 82.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 72.0, 70.0, 71.0, 92.0, 68.0, 72.0, 87.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 86.0, 89.0, 86.0, 90.0, 86.0, 89.0, 89.0, 89.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 103.0, 99.0, 112.0, 172.0, 100.0, 98.0, 100.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 93.0, 89.0, 91.0, 99.0, 91.0, 91.0, 90.0, 91.0, 92.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 56.0, 57.0, 58.0, 58.0, 60.0, 75.0, 75.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 71.0, 84.0, 80.0, 76.0, 69.0, 70.0, 70.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 106.0, 77.0, 97.0, 79.0, 98.0, 78.0, 87.0, 104.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 162.0, 161.0, 137.0, 167.0, 171.0, 160.0, 199.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 177.0, 172.0, 174.0, 177.0, 173.0, 175.0, 194.0, 223.0, 173.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 97.0, 89.0, 93.0, 75.0, 69.0, 68.0, 69.0, 71.0], "value": 76.9, "unit": "score"}]}]} 09:57:46 INFO - Return code: 0 09:57:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:46 ERROR - # TBPL SUCCESS # 09:57:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:46 INFO - 2016-10-07 09:57:46.874 screenresolution[2256:19763] starting screenresolution argv=screenresolution get 09:57:46 INFO - 2016-10-07 09:57:46.876 screenresolution[2256:19763] Display 0: 1600x1200x32@60 09:57:46 INFO - 2016-10-07 09:57:46.882 screenresolution[2257:19766] starting screenresolution argv=screenresolution list 09:57:46 INFO - Available Modes on Display 0 09:57:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:47 INFO - Intel Iris: 09:57:47 INFO - Chipset Model: Intel Iris 09:57:47 INFO - Type: GPU 09:57:47 INFO - Bus: Built-In 09:57:47 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:47 INFO - Vendor: Intel (0x8086) 09:57:47 INFO - Device ID: 0x0a2e 09:57:47 INFO - Revision ID: 0x0009 09:57:47 INFO - Displays: 09:57:47 INFO - Display: 09:57:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:47 INFO - Display Serial Number: 1600x1200 60 09:57:47 INFO - Main Display: Yes 09:57:47 INFO - Mirror: Off 09:57:47 INFO - Online: Yes 09:57:47 INFO - Rotation: Supported 09:57:47 INFO - Return code: 0 09:57:47 INFO - Running post-action listener: _resource_record_post_action 09:57:47 INFO - [mozharness: 2016-10-07 16:57:47.116863Z] Finished run-tests step (success) 09:57:47 INFO - Running post-run listener: _resource_record_post_run 09:57:47 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 565248; Write bytes: 707912704; Read time: 4; Write time: 2115 09:57:47 INFO - TinderboxPrint: CPU usage
54.7% 09:57:47 INFO - TinderboxPrint: I/O read bytes / time
565,248 / 4 09:57:47 INFO - TinderboxPrint: I/O write bytes / time
707,912,704 / 2,115 09:57:47 INFO - TinderboxPrint: CPU idle
2,195.6 (45.3%) 09:57:47 INFO - TinderboxPrint: CPU system
982.2 (20.3%) 09:57:47 INFO - TinderboxPrint: CPU user
1,664.0 (34.4%) 09:57:47 INFO - TinderboxPrint: Swap in / out
407,941,120 / 0 09:57:47 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400124416; Write bytes: 394774528; Read time: 29680; Write time: 702 09:57:47 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 565248; Write bytes: 296242176; Read time: 4; Write time: 1371 09:57:47 INFO - Running post-run listener: _upload_blobber_files 09:57:47 INFO - Blob upload gear active. 09:57:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:57:48 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:57:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:57:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:49 INFO - (blobuploader) - INFO - Done attempting. 09:57:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:57:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:50 INFO - (blobuploader) - INFO - Done attempting. 09:57:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:57:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:52 INFO - (blobuploader) - INFO - Done attempting. 09:57:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:52 INFO - Return code: 0 09:57:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb22c6c8bfd9d64c190ebb7567af5e290089606d94f4a2ec4a5c4f22b37e1ca13919033c39172ed27d930d5a3838d1715b4ee96893aa8b5fd5c83cb063edb288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da3a1c261d7aee6d7a2638e45f1625d54e9e1f8f5fd2eaca62752a0ca81432cae4f46b922335e9d33071cf1cecf8e2737ff5cffb92deaf1226a8fd7fe9a36892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da15a121898cc464063893fdaf1b590b1637b9fcacd39e3a9928b1032e6a2f96b80c48d4e34732b0c5b5d18ec113d81f77758f655f9c12dc17478b170e578e93"} 09:57:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:52 INFO - Contents: 09:57:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb22c6c8bfd9d64c190ebb7567af5e290089606d94f4a2ec4a5c4f22b37e1ca13919033c39172ed27d930d5a3838d1715b4ee96893aa8b5fd5c83cb063edb288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da3a1c261d7aee6d7a2638e45f1625d54e9e1f8f5fd2eaca62752a0ca81432cae4f46b922335e9d33071cf1cecf8e2737ff5cffb92deaf1226a8fd7fe9a36892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da15a121898cc464063893fdaf1b590b1637b9fcacd39e3a9928b1032e6a2f96b80c48d4e34732b0c5b5d18ec113d81f77758f655f9c12dc17478b170e578e93"} 09:57:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:52 INFO - Copying logs to upload dir... 09:57:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.506468 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-10-07 09:57:52.359019) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 09:57:52.362688) ========= 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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb22c6c8bfd9d64c190ebb7567af5e290089606d94f4a2ec4a5c4f22b37e1ca13919033c39172ed27d930d5a3838d1715b4ee96893aa8b5fd5c83cb063edb288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da3a1c261d7aee6d7a2638e45f1625d54e9e1f8f5fd2eaca62752a0ca81432cae4f46b922335e9d33071cf1cecf8e2737ff5cffb92deaf1226a8fd7fe9a36892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da15a121898cc464063893fdaf1b590b1637b9fcacd39e3a9928b1032e6a2f96b80c48d4e34732b0c5b5d18ec113d81f77758f655f9c12dc17478b170e578e93"} build_url:https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/bhLbi3E2QwqoY2BiwhGsYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb22c6c8bfd9d64c190ebb7567af5e290089606d94f4a2ec4a5c4f22b37e1ca13919033c39172ed27d930d5a3838d1715b4ee96893aa8b5fd5c83cb063edb288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da3a1c261d7aee6d7a2638e45f1625d54e9e1f8f5fd2eaca62752a0ca81432cae4f46b922335e9d33071cf1cecf8e2737ff5cffb92deaf1226a8fd7fe9a36892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da15a121898cc464063893fdaf1b590b1637b9fcacd39e3a9928b1032e6a2f96b80c48d4e34732b0c5b5d18ec113d81f77758f655f9c12dc17478b170e578e93"}' ========= master_lag: 0.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 09:57:52.848573) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:57:52.849089) ========= 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.Ss3qgDtiyf/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.zPOT4AR8ca/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.005549 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:57:52.900976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 09:57:52.901746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 09:57:52.902454) ========= ========= Total master_lag: 0.65 =========