builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0303 starttime: 1479747128.68 results: success (0) buildid: 20161121061709 builduid: 8af0ecf1e765445e942611f487c41a33 revision: cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.678453) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.678935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.679238) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.707214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.707617) ========= 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.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-21 08:52:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.4M=0s 2016-11-21 08:52:08 (58.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088656 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.820424) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.820746) ========= 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.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.025220 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:08.872252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 08:52:08.872699) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-21 08:52:08,972 truncating revision to first 12 chars 2016-11-21 08:52:08,972 Setting DEBUG logging. 2016-11-21 08:52:08,972 attempt 1/10 2016-11-21 08:52:08,973 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb5d57c8c754?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 08:52:10,014 unpacking tar archive at: mozilla-central-cb5d57c8c754/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317021 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 08:52:10.215587) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:10.215913) ========= script_repo_revision: cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:10.216324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:10.216588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 08:52:10.235134) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2016-11-21 08:52:10.235476) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:52:10 INFO - MultiFileLogger online at 20161121 08:52:10 in /builds/slave/test 08:52:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:52:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:10 INFO - {'append_to_log': False, 08:52:10 INFO - 'base_work_dir': '/builds/slave/test', 08:52:10 INFO - 'blob_upload_branch': 'Firefox', 08:52:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:10 INFO - 'branch': 'Firefox', 08:52:10 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:10 INFO - 'config_files': ('talos/mac_config.py',), 08:52:10 INFO - 'default_actions': ('clobber', 08:52:10 INFO - 'read-buildbot-config', 08:52:10 INFO - 'download-and-extract', 08:52:10 INFO - 'populate-webroot', 08:52:10 INFO - 'create-virtualenv', 08:52:10 INFO - 'install', 08:52:10 INFO - 'run-tests'), 08:52:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:10 INFO - 'download_minidump_stackwalk': True, 08:52:10 INFO - 'download_symbols': 'ondemand', 08:52:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:10 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:10 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:10 INFO - 'installer_path': 'installer.exe', 08:52:10 INFO - 'log_level': 'info', 08:52:10 INFO - 'log_name': 'talos', 08:52:10 INFO - 'log_to_console': True, 08:52:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:10 INFO - 'opt_config_files': (), 08:52:10 INFO - 'pip_index': False, 08:52:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:10 INFO - 'cmd': ('bash', 08:52:10 INFO - '-c', 08:52:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:10 INFO - 'enabled': True, 08:52:10 INFO - 'halt_on_failure': False, 08:52:10 INFO - 'name': 'check_screen_resolution'},), 08:52:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:10 INFO - 'cmd': ('bash', 08:52:10 INFO - '-c', 08:52:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:10 INFO - 'enabled': True, 08:52:10 INFO - 'halt_on_failure': False, 08:52:10 INFO - 'name': 'check_screen_resolution'},), 08:52:10 INFO - 'run_cmd_checks_enabled': True, 08:52:10 INFO - 'sps_profile': False, 08:52:10 INFO - 'sps_profile_interval': 0, 08:52:10 INFO - 'suite': 'dromaeojs-e10s', 08:52:10 INFO - 'system_bits': '32', 08:52:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:10 INFO - 'title': 't-yosemite-r7-0303', 08:52:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:10 INFO - 'use_talos_json': True, 08:52:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:10 INFO - 'work_dir': 'build'} 08:52:10 INFO - [mozharness: 2016-11-21 16:52:10.449678Z] Running clobber step. 08:52:10 INFO - Running pre-action listener: _resource_record_pre_action 08:52:10 INFO - Running main action method: clobber 08:52:10 INFO - rmtree: /builds/slave/test/build 08:52:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:12 INFO - Running post-action listener: _resource_record_post_action 08:52:12 INFO - [mozharness: 2016-11-21 16:52:12.860315Z] Finished clobber step (success) 08:52:12 INFO - [mozharness: 2016-11-21 16:52:12.860450Z] Running read-buildbot-config step. 08:52:12 INFO - Running pre-action listener: _resource_record_pre_action 08:52:12 INFO - Running main action method: read_buildbot_config 08:52:12 INFO - Using buildbot properties: 08:52:12 INFO - { 08:52:12 INFO - "project": "", 08:52:12 INFO - "product": "firefox", 08:52:12 INFO - "script_repo_revision": "production", 08:52:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:52:12 INFO - "repository": "", 08:52:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:52:12 INFO - "buildid": "20161121061709", 08:52:12 INFO - "pgo_build": "False", 08:52:12 INFO - "basedir": "/builds/slave/test", 08:52:12 INFO - "buildnumber": 176, 08:52:12 INFO - "slavename": "t-yosemite-r7-0303", 08:52:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:52:12 INFO - "platform": "yosemite_r7", 08:52:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:52:12 INFO - "branch": "mozilla-central", 08:52:12 INFO - "repo_path": "mozilla-central", 08:52:12 INFO - "revision": "cb5d57c8c7547bf5d6d86a04062c10fcd98e6667", 08:52:12 INFO - "stage_platform": "macosx64", 08:52:12 INFO - "builduid": "8af0ecf1e765445e942611f487c41a33", 08:52:12 INFO - "slavebuilddir": "test" 08:52:12 INFO - } 08:52:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:52:12 INFO - Running post-action listener: _resource_record_post_action 08:52:12 INFO - [mozharness: 2016-11-21 16:52:12.863734Z] Finished read-buildbot-config step (success) 08:52:12 INFO - [mozharness: 2016-11-21 16:52:12.863864Z] Running download-and-extract step. 08:52:12 INFO - Running pre-action listener: _resource_record_pre_action 08:52:12 INFO - Running main action method: download_and_extract 08:52:12 INFO - mkdir: /builds/slave/test/build/tests 08:52:12 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')]} 08:52:12 INFO - trying https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:52:13 INFO - Downloaded 1199 bytes. 08:52:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:52:13 INFO - Using the following test package requirements: 08:52:13 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:52:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:52:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:52:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'jsshell-mac.zip'], 08:52:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:52:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:52:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:52:13 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:52:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:52:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:52:13 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:52:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:52:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:52:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:13 INFO - Fetch https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:52:14 INFO - Http code: 200 08:52:14 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 08:52:14 INFO - x-amz-cf-id: jzMvgZQQ_98jHlDKYpMyqV4yZJ1QR8-YHzbdgP22JSmei62Hey6hKA== 08:52:14 INFO - x-amz-version-id: zKxqNosaexmkq.u5sirRi5mNDmEgPfNM 08:52:14 INFO - Expected file size: 16265579 08:52:14 INFO - Obtained file size: 16265579 08:52:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:52:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:52:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:21 INFO - Fetch https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:52:21 INFO - Http code: 200 08:52:21 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 08:52:21 INFO - x-amz-cf-id: z__asLyOphGRBvHET6ZyMUpdN4YyAeLmFVZukbLyUHAaxfN0eyOfCA== 08:52:21 INFO - x-amz-version-id: zKxqNosaexmkq.u5sirRi5mNDmEgPfNM 08:52:22 INFO - Expected file size: 16265579 08:52:22 INFO - Obtained file size: 16265579 08:52:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:52:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:52:28 INFO - Fetch https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:52:29 INFO - Http code: 200 08:52:29 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 08:52:29 INFO - x-amz-cf-id: F6_6xKMJfhmzFytj7FOU6wCg7htpxioWgPs4T7HhTQ_aNmhL6gzunw== 08:52:29 INFO - x-amz-version-id: iL54nymbQAhBxeHU4X2QkFqnJ7jzHU6J 08:52:29 INFO - Expected file size: 14409392 08:52:29 INFO - Obtained file size: 14409392 08:52: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')]} 08:52:30 INFO - trying https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:52:30 INFO - Downloading https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:52:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:53:02 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg: The read operation timed out 08:53:02 INFO - retry: attempt #1 caught exception: The read operation timed out 08:53:02 INFO - retry: Failed, sleeping 30 seconds before retrying 08:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #2 08:53:35 INFO - Downloaded 107542278 bytes. 08:53:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:53:35 INFO - mkdir: /builds/slave/test/properties 08:53:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:53:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:53:35 INFO - Contents: 08:53:35 INFO - build_url:https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:53:36 INFO - Running post-action listener: _resource_record_post_action 08:53:36 INFO - Running post-action listener: set_extra_try_arguments 08:53:36 INFO - [mozharness: 2016-11-21 16:53:36.205445Z] Finished download-and-extract step (success) 08:53:36 INFO - [mozharness: 2016-11-21 16:53:36.205558Z] Running populate-webroot step. 08:53:36 INFO - Running pre-action listener: _resource_record_pre_action 08:53:36 INFO - Running main action method: populate_webroot 08:53:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:36 INFO - '--tppagecycles', 08:53:36 INFO - '7'], 08:53:36 INFO - 'tests': ['tsvgm']}}, 08:53:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['damp', 'tps']}, 08:53:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'tests': ['damp', 'tps']}, 08:53:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['dromaeo_dom']}, 08:53:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['basic_compositor_video']}, 08:53:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:53:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['a11yr', 08:53:36 INFO - 'ts_paint', 08:53:36 INFO - 'tpaint', 08:53:36 INFO - 'sessionrestore', 08:53:36 INFO - 'sessionrestore_no_auto_restore', 08:53:36 INFO - 'tabpaint']}, 08:53:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['tsvgx', 08:53:36 INFO - 'tsvgr_opacity', 08:53:36 INFO - 'tart', 08:53:36 INFO - 'tscrollx', 08:53:36 INFO - 'cart']}, 08:53:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:36 INFO - 'tsvgr_opacity', 08:53:36 INFO - 'tart', 08:53:36 INFO - 'tscrollx', 08:53:36 INFO - 'cart']}, 08:53:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'talos_options': ['--disable-e10s'], 08:53:36 INFO - 'tests': ['tp5o']}, 08:53:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:53:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'talos_options': ['--disable-e10s', 08:53:36 INFO - '--xperf_path', 08:53:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:36 INFO - 'tests': ['tp5n']}, 08:53:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:53:36 INFO - 'talos_options': ['--xperf_path', 08:53:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:36 INFO - 'tests': ['tp5n']}}, 08:53:36 INFO - 'talos.zip': {'path': '', 08:53:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:36 INFO - Running post-action listener: _resource_record_post_action 08:53:36 INFO - [mozharness: 2016-11-21 16:53:36.223523Z] Finished populate-webroot step (success) 08:53:36 INFO - [mozharness: 2016-11-21 16:53:36.223633Z] Running create-virtualenv step. 08:53:36 INFO - Running pre-action listener: _resource_record_pre_action 08:53:36 INFO - Running main action method: create_virtualenv 08:53:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:36 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 08:53:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:36 INFO - Using real prefix '/tools/python27' 08:53:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:39 INFO - Installing pip.................done. 08:53:39 INFO - Return code: 0 08:53:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:53:40 INFO - Reading from file tmpfile_stdout 08:53:40 INFO - Output received: 08:53:40 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) 08:53:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53:40 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')]} 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 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')]} 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:40 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 08:53:40 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 08:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:40 INFO - 'HOME': '/Users/cltbld', 08:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:40 INFO - 'LOGNAME': 'cltbld', 08:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:40 INFO - 'MOZ_NO_REMOTE': '1', 08:53:40 INFO - 'NO_EM_RESTART': '1', 08:53:40 INFO - 'PAGER': '/bin/cat', 08:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:40 INFO - 'PWD': '/builds/slave/test', 08:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:40 INFO - 'SHELL': '/bin/bash', 08:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:40 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:40 INFO - 'USER': 'cltbld', 08:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:40 INFO - 'XPC_FLAGS': '0x0', 08:53:40 INFO - 'XPC_SERVICE_NAME': '0', 08:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:40 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 08:53:40 INFO - Cleaning up... 08:53:40 INFO - Return code: 0 08:53:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:53:40 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')]} 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 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')]} 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:40 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 08:53:40 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 08:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:40 INFO - 'HOME': '/Users/cltbld', 08:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:40 INFO - 'LOGNAME': 'cltbld', 08:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:40 INFO - 'MOZ_NO_REMOTE': '1', 08:53:40 INFO - 'NO_EM_RESTART': '1', 08:53:40 INFO - 'PAGER': '/bin/cat', 08:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:40 INFO - 'PWD': '/builds/slave/test', 08:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:40 INFO - 'SHELL': '/bin/bash', 08:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:40 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:40 INFO - 'USER': 'cltbld', 08:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:40 INFO - 'XPC_FLAGS': '0x0', 08:53:40 INFO - 'XPC_SERVICE_NAME': '0', 08:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:40 INFO - Downloading/unpacking psutil>=3.1.1 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:44 INFO - Installing collected packages: psutil 08:53:44 INFO - Running setup.py install for psutil 08:53:44 INFO - building 'psutil._psutil_osx' extension 08:53:44 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 08:53:45 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 08:53:45 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 08:53:45 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 08:53:45 INFO - building 'psutil._psutil_posix' extension 08:53:45 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 08:53:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:45 INFO - ^ 08:53:45 INFO - 1 warning generated. 08:53:45 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 08:53:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:45 INFO - Successfully installed psutil 08:53:45 INFO - Cleaning up... 08:53:45 INFO - Return code: 0 08:53:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:45 INFO - 'HOME': '/Users/cltbld', 08:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:45 INFO - 'LOGNAME': 'cltbld', 08:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:45 INFO - 'MOZ_NO_REMOTE': '1', 08:53:45 INFO - 'NO_EM_RESTART': '1', 08:53:45 INFO - 'PAGER': '/bin/cat', 08:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:45 INFO - 'PWD': '/builds/slave/test', 08:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:45 INFO - 'SHELL': '/bin/bash', 08:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:45 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:45 INFO - 'USER': 'cltbld', 08:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:45 INFO - 'XPC_FLAGS': '0x0', 08:53:45 INFO - 'XPC_SERVICE_NAME': '0', 08:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:48 INFO - Installing collected packages: mozsystemmonitor 08:53:48 INFO - Running setup.py install for mozsystemmonitor 08:53:48 INFO - Successfully installed mozsystemmonitor 08:53:48 INFO - Cleaning up... 08:53:48 INFO - Return code: 0 08:53:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:48 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')]} 08:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:48 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')]} 08:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:48 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:48 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 08:53:48 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 08:53:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:48 INFO - 'HOME': '/Users/cltbld', 08:53:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:48 INFO - 'LOGNAME': 'cltbld', 08:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:48 INFO - 'MOZ_NO_REMOTE': '1', 08:53:48 INFO - 'NO_EM_RESTART': '1', 08:53:48 INFO - 'PAGER': '/bin/cat', 08:53:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:48 INFO - 'PWD': '/builds/slave/test', 08:53:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:48 INFO - 'SHELL': '/bin/bash', 08:53:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:48 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:48 INFO - 'USER': 'cltbld', 08:53:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:48 INFO - 'XPC_FLAGS': '0x0', 08:53:48 INFO - 'XPC_SERVICE_NAME': '0', 08:53:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:49 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:51 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 08:53:51 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 08:53:51 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 08:53:51 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 08:53:51 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 08:53:51 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 08:53:52 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:52 INFO - warning: no files found matching '*.txt' 08:53:52 INFO - no previously-included directories found matching 'build' 08:53:52 INFO - no previously-included directories found matching 'dist' 08:53:52 INFO - no previously-included directories found matching '.git*' 08:53:52 INFO - Installing collected packages: jsonschema, functools32 08:53:52 INFO - Running setup.py install for functools32 08:53:52 INFO - warning: no files found matching '*.txt' 08:53:52 INFO - no previously-included directories found matching 'build' 08:53:52 INFO - no previously-included directories found matching 'dist' 08:53:52 INFO - no previously-included directories found matching '.git*' 08:53:52 INFO - Successfully installed jsonschema functools32 08:53:52 INFO - Cleaning up... 08:53:52 INFO - Return code: 0 08:53:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:52 INFO - 'HOME': '/Users/cltbld', 08:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:52 INFO - 'LOGNAME': 'cltbld', 08:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:52 INFO - 'MOZ_NO_REMOTE': '1', 08:53:52 INFO - 'NO_EM_RESTART': '1', 08:53:52 INFO - 'PAGER': '/bin/cat', 08:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:52 INFO - 'PWD': '/builds/slave/test', 08:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:52 INFO - 'SHELL': '/bin/bash', 08:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:52 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:52 INFO - 'USER': 'cltbld', 08:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:52 INFO - 'XPC_FLAGS': '0x0', 08:53:52 INFO - 'XPC_SERVICE_NAME': '0', 08:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:52 INFO - Cleaning up... 08:53:52 INFO - Return code: 0 08:53:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:52 INFO - 'HOME': '/Users/cltbld', 08:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:52 INFO - 'LOGNAME': 'cltbld', 08:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:52 INFO - 'MOZ_NO_REMOTE': '1', 08:53:52 INFO - 'NO_EM_RESTART': '1', 08:53:52 INFO - 'PAGER': '/bin/cat', 08:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:52 INFO - 'PWD': '/builds/slave/test', 08:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:52 INFO - 'SHELL': '/bin/bash', 08:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:52 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:52 INFO - 'USER': 'cltbld', 08:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:52 INFO - 'XPC_FLAGS': '0x0', 08:53:52 INFO - 'XPC_SERVICE_NAME': '0', 08:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:52 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:55 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:56 INFO - Downloading docopt-0.6.1.tar.gz 08:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:56 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:56 INFO - Running setup.py install for blobuploader 08:53:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:56 INFO - Running setup.py install for requests 08:53:57 INFO - Running setup.py install for docopt 08:53:57 INFO - Successfully installed blobuploader requests docopt 08:53:57 INFO - Cleaning up... 08:53:57 INFO - Return code: 0 08:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:57 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')]} 08:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:57 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')]} 08:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:57 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:53:57 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 08:53:57 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 08:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:57 INFO - 'HOME': '/Users/cltbld', 08:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:57 INFO - 'LOGNAME': 'cltbld', 08:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:57 INFO - 'MOZ_NO_REMOTE': '1', 08:53:57 INFO - 'NO_EM_RESTART': '1', 08:53:57 INFO - 'PAGER': '/bin/cat', 08:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:57 INFO - 'PWD': '/builds/slave/test', 08:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:57 INFO - 'SHELL': '/bin/bash', 08:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:53:57 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:57 INFO - 'USER': 'cltbld', 08:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:57 INFO - 'XPC_FLAGS': '0x0', 08:53:57 INFO - 'XPC_SERVICE_NAME': '0', 08:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:57 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-EQgYgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-v3Fwuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-N4cFe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-l0Zu38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-ulwH_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-57gek9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-p3wgxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-x1YXsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-02xO0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-uK2IYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:58 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-57unu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-NymUpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-NLk8Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-fZ8IKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-T8_VcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-fRbKT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:59 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-m5uVOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:59 INFO - Running setup.py install for manifestparser 08:53:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:59 INFO - Running setup.py install for mozcrash 08:53:59 INFO - Running setup.py install for mozdebug 08:54:00 INFO - Running setup.py install for mozdevice 08:54:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Running setup.py install for mozfile 08:54:00 INFO - Running setup.py install for mozhttpd 08:54:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Running setup.py install for mozinfo 08:54:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Running setup.py install for mozInstall 08:54:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:54:00 INFO - Running setup.py install for mozleak 08:54:01 INFO - Running setup.py install for mozlog 08:54:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Running setup.py install for moznetwork 08:54:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Running setup.py install for mozprocess 08:54:01 INFO - Running setup.py install for mozprofile 08:54:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Running setup.py install for mozrunner 08:54:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:54:01 INFO - Running setup.py install for mozscreenshot 08:54:01 INFO - Running setup.py install for moztest 08:54:02 INFO - Running setup.py install for mozversion 08:54:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:54:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:54:02 INFO - Cleaning up... 08:54:02 INFO - Return code: 0 08:54:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:02 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')]} 08:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:02 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')]} 08:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:02 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:54:02 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 08:54:02 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 08:54:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:54:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:02 INFO - 'HOME': '/Users/cltbld', 08:54:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:02 INFO - 'LOGNAME': 'cltbld', 08:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:02 INFO - 'MOZ_NO_REMOTE': '1', 08:54:02 INFO - 'NO_EM_RESTART': '1', 08:54:02 INFO - 'PAGER': '/bin/cat', 08:54:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:02 INFO - 'PWD': '/builds/slave/test', 08:54:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:02 INFO - 'SHELL': '/bin/bash', 08:54:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:54:02 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:54:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:02 INFO - 'USER': 'cltbld', 08:54:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:02 INFO - 'XPC_FLAGS': '0x0', 08:54:02 INFO - 'XPC_SERVICE_NAME': '0', 08:54:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:02 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-Vjjt0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:02 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)) 08:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:02 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-Vc0_Jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:02 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-2Q04ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:02 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)) 08:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:02 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-lD9Glw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-8KH3qN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-doDrjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-yAa1RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-TV2CnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-tEf50M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-T_FE6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-HNwuhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-V4Cvc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-GECa9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:03 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)) 08:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:03 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-kPbfwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:04 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-5V1fma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:04 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)) 08:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:04 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-iQIgDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:04 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)) 08:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:04 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-WqcpkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:04 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)) 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:04 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)) 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:06 INFO - Downloading blessings-1.6.tar.gz 08:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:07 INFO - Installing collected packages: blessings 08:54:07 INFO - Running setup.py install for blessings 08:54:07 INFO - Successfully installed blessings 08:54:07 INFO - Cleaning up... 08:54:07 INFO - Return code: 0 08:54:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:07 INFO - Reading from file tmpfile_stdout 08:54:07 INFO - Current package versions: 08:54:07 INFO - blessings == 1.6 08:54:07 INFO - blobuploader == 1.2.4 08:54:07 INFO - docopt == 0.6.1 08:54:07 INFO - functools32 == 3.2.3-2 08:54:07 INFO - jsonschema == 2.5.1 08:54:07 INFO - manifestparser == 1.1 08:54:07 INFO - mozInstall == 1.12 08:54:07 INFO - mozcrash == 1.0 08:54:07 INFO - mozdebug == 0.1 08:54:07 INFO - mozdevice == 0.49 08:54:07 INFO - mozfile == 1.2 08:54:07 INFO - mozhttpd == 0.7 08:54:07 INFO - mozinfo == 0.9 08:54:07 INFO - mozleak == 0.1 08:54:07 INFO - mozlog == 3.4 08:54:07 INFO - moznetwork == 0.27 08:54:07 INFO - mozprocess == 0.23 08:54:07 INFO - mozprofile == 0.28 08:54:07 INFO - mozrunner == 6.13 08:54:07 INFO - mozscreenshot == 0.1 08:54:07 INFO - mozsystemmonitor == 0.3 08:54:07 INFO - moztest == 0.7 08:54:07 INFO - mozversion == 1.4 08:54:07 INFO - psutil == 3.1.1 08:54:07 INFO - requests == 1.2.3 08:54:07 INFO - wsgiref == 0.1.2 08:54:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:07 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 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:54:07 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 08:54:07 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 08:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:07 INFO - 'HOME': '/Users/cltbld', 08:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:07 INFO - 'LOGNAME': 'cltbld', 08:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:07 INFO - 'MOZ_NO_REMOTE': '1', 08:54:07 INFO - 'NO_EM_RESTART': '1', 08:54:07 INFO - 'PAGER': '/bin/cat', 08:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:07 INFO - 'PWD': '/builds/slave/test', 08:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:07 INFO - 'SHELL': '/bin/bash', 08:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:54:07 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:07 INFO - 'USER': 'cltbld', 08:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:07 INFO - 'XPC_FLAGS': '0x0', 08:54:07 INFO - 'XPC_SERVICE_NAME': '0', 08:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 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)) 08:54:07 INFO - Cleaning up... 08:54:07 INFO - Return code: 0 08:54:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b58c3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b502570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbca84a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, '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.pN4i6v86mw/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.nTIXhTdIcy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 08:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:07 INFO - 'HOME': '/Users/cltbld', 08:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:07 INFO - 'LOGNAME': 'cltbld', 08:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:07 INFO - 'MOZ_NO_REMOTE': '1', 08:54:07 INFO - 'NO_EM_RESTART': '1', 08:54:07 INFO - 'PAGER': '/bin/cat', 08:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:07 INFO - 'PWD': '/builds/slave/test', 08:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:07 INFO - 'SHELL': '/bin/bash', 08:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:54:07 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:07 INFO - 'USER': 'cltbld', 08:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:07 INFO - 'XPC_FLAGS': '0x0', 08:54:07 INFO - 'XPC_SERVICE_NAME': '0', 08:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:08 INFO - Cleaning up... 08:54:08 INFO - Return code: 0 08:54:08 INFO - Running post-action listener: _resource_record_post_action 08:54:08 INFO - Running post-action listener: _start_resource_monitoring 08:54:08 INFO - Starting resource monitoring. 08:54:08 INFO - [mozharness: 2016-11-21 16:54:08.160999Z] Finished create-virtualenv step (success) 08:54:08 INFO - [mozharness: 2016-11-21 16:54:08.161977Z] Running install step. 08:54:08 INFO - Running pre-action listener: _resource_record_pre_action 08:54:08 INFO - Running main action method: install 08:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:08 INFO - Reading from file tmpfile_stdout 08:54:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:08 INFO - Reading from file tmpfile_stdout 08:54:08 INFO - Output received: 08:54:08 INFO - Usage: mozinstall [options] installer 08:54:08 INFO - Options: 08:54:08 INFO - -h, --help show this help message and exit 08:54:08 INFO - -d DEST, --destination=DEST 08:54:08 INFO - Directory to install application into. [default: 08:54:08 INFO - "/builds/slave/test"] 08:54:08 INFO - --app=APP Application being installed. [default: firefox] 08:54:08 INFO - mkdir: /builds/slave/test/build/application 08:54:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:54:41 INFO - Reading from file tmpfile_stdout 08:54:41 INFO - Output received: 08:54:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:54:41 INFO - Running post-action listener: _resource_record_post_action 08:54:41 INFO - [mozharness: 2016-11-21 16:54:41.005276Z] Finished install step (success) 08:54:41 INFO - [mozharness: 2016-11-21 16:54:41.005460Z] Running run-tests step. 08:54:41 INFO - Running pre-action listener: _resource_record_pre_action 08:54:41 INFO - Running main action method: run_tests 08:54:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:41 INFO - 2016-11-21 08:54:41.019 screenresolution[1631:7566] starting screenresolution argv=screenresolution get 08:54:41 INFO - 2016-11-21 08:54:41.022 screenresolution[1631:7566] Display 0: 1600x1200x32@60 08:54:41 INFO - 2016-11-21 08:54:41.028 screenresolution[1632:7568] starting screenresolution argv=screenresolution list 08:54:41 INFO - Available Modes on Display 0 08:54:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:41 INFO - Intel Iris: 08:54:41 INFO - Chipset Model: Intel Iris 08:54:41 INFO - Type: GPU 08:54:41 INFO - Bus: Built-In 08:54:41 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:41 INFO - Vendor: Intel (0x8086) 08:54:41 INFO - Device ID: 0x0a2e 08:54:41 INFO - Revision ID: 0x0009 08:54:41 INFO - Displays: 08:54:41 INFO - Display: 08:54:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:41 INFO - Display Serial Number: 1600x1200 60 08:54:41 INFO - Main Display: Yes 08:54:41 INFO - Mirror: Off 08:54:41 INFO - Online: Yes 08:54:41 INFO - Rotation: Supported 08:54:41 INFO - Return code: 0 08:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:41 INFO - Python 2.7.3 08:54:41 INFO - Return code: 0 08:54:41 INFO - grabbing minidump binary from tooltool 08:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:41 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 0x10b3a2d30>, '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 0x10b209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:41 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 08:54:41 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 08:54:41 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 08:54:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:41 INFO - Return code: 0 08:54:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 08:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0303 --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 08:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pN4i6v86mw/Render', 08:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:41 INFO - 'HOME': '/Users/cltbld', 08:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:41 INFO - 'LOGNAME': 'cltbld', 08:54:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:41 INFO - 'MOZ_NO_REMOTE': '1', 08:54:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:41 INFO - 'NO_EM_RESTART': '1', 08:54:41 INFO - 'PAGER': '/bin/cat', 08:54:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:41 INFO - 'PWD': '/builds/slave/test', 08:54:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:41 INFO - 'SHELL': '/bin/bash', 08:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nTIXhTdIcy/Listeners', 08:54:41 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 08:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:41 INFO - 'USER': 'cltbld', 08:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:41 INFO - 'XPC_FLAGS': '0x0', 08:54:41 INFO - 'XPC_SERVICE_NAME': '0', 08:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 08:54:41 INFO - mozversion application_buildid: 20161121061709 08:54:41 INFO - mozversion application_changeset: cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 08:54:41 INFO - mozversion application_display_name: Nightly 08:54:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:41 INFO - mozversion application_name: Firefox 08:54:41 INFO - mozversion application_remotingname: firefox 08:54:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:54:41 INFO - mozversion application_vendor: Mozilla 08:54:41 INFO - mozversion application_version: 53.0a1 08:54:41 INFO - mozversion platform_buildid: 20161121061709 08:54:41 INFO - mozversion platform_changeset: cb5d57c8c7547bf5d6d86a04062c10fcd98e6667 08:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:54:41 INFO - mozversion platform_version: 53.0a1 08:54:41 INFO - using testdate: 1479747281 08:54:41 INFO - actual date: 1479747281 08:54:41 INFO - starting webserver on 'localhost:49238' 08:54:41 INFO - SUITE-START | Running 2 tests 08:54:41 INFO - TEST-START | dromaeo_css 08:54:41 INFO - operating with platform_type : mac_ 08:54:41 INFO - Initialising browser for dromaeo_css test... 08:54:41 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpOr9kO0/profile http://localhost:49238/getInfo.html) 08:54:45 INFO - PROCESS | 1642 | 2016-11-21 08:54:45.046 plugin-container[1644:7745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 08:54:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:45 INFO - PROCESS | 1642 | 2016-11-21 08:54:45.049 plugin-container[1644:7745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 08:54:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:45 INFO - PROCESS | 1642 | 2016-11-21 08:54:45.050 plugin-container[1644:7745] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:45 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 08:54:45 INFO - PROCESS | 1642 | colorDepth:24 08:54:45 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 08:54:45 INFO - PROCESS | 1642 | __metrics 08:54:45 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 08:54:46 INFO - TEST-INFO | 1642: exit 0 08:54:46 INFO - Browser initialized. 08:54:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpOr9kO0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:48 INFO - PROCESS | 1645 | 2016-11-21 08:54:48.723 plugin-container[1646:7862] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 08:54:48 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:48 INFO - PROCESS | 1645 | 2016-11-21 08:54:48.723 plugin-container[1646:7862] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:54:48 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:48 INFO - PROCESS | 1645 | 2016-11-21 08:54:48.723 plugin-container[1646:7862] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:58 INFO - PROCESS | 1645 | RSS: Main: 242741248 08:54:58 INFO - PROCESS | 1645 | 08:55:48 INFO - PROCESS | 1645 | 1479747348054 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] 08:58:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 08:58:04 INFO - PROCESS | 1645 | RSS: Main: 249667584 08:58:04 INFO - PROCESS | 1645 | 09:01:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 09:01:07 INFO - PROCESS | 1645 | RSS: Main: 259874816 09:01:07 INFO - PROCESS | 1645 | 09:04:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 09:04:10 INFO - PROCESS | 1645 | RSS: Main: 264294400 09:04:10 INFO - PROCESS | 1645 | 09:07:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 09:07:12 INFO - PROCESS | 1645 | RSS: Main: 263073792 09:07:12 INFO - PROCESS | 1645 | 09:10:15 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 09:10:16 INFO - PROCESS | 1645 | RSS: Main: 267898880 09:10:16 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 09:13:19 INFO - PROCESS | 1645 | RSS: Main: 269611008 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | __start_tp_report 09:13:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 09:13:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 09:13:19 INFO - PROCESS | 1645 | |i|pagename|runs| 09:13:19 INFO - PROCESS | 1645 | |0;dojo.html;3342.5487559179505;3353.564036675232;3379.79721216727;3396.473546600277;3400.979706088173;4952.95987842553;4955.405351357842;5654.943450565494;5768.798092066779;5780.161876527906;5626.887462250753;5736.1682556029355;5781.62419442736;5823.417658234174;5927.525797936166;5161.200013997817;5194.584433245332;5199.818006369778;5267.315249017623;5363.034653762321;5358.839234822962;5359;5364.704941228231;5374.7043912584795;5380.408155102936;4541.931871021938;4973.975130124362;5346.251524786533;5349.625526213147;5396.082665946798;4274.551172126944;5008.599312055063;5137.2037334213255;5140.588752899759;5163.483651634844;5028.019536190444;5030.195168773015;5065;5091.770870310808;5123.1546794778815;4138.937915931264;5688.459596338341;5729.341125770507;5784.479396854261;5868.383819698955;6174.783882564134;6205.22434695663;6240.157578726859;6241.469475094577;6250.9062364064575;6125.47964319526;6237.4698150656795;6247.094171345175;6252.906206406908;6283.088952101965;5861.120831875211;5931.970340148272;5933.0803725421765;5936.22829032223;6003;6343.429091381798;6371.203599550056;6372.58578192416;6393.520485963479;6398.392152745481;6054.152418661303;6103.155845961327;6107.492261292505;6169.074638804215;6184;6116.969415152896;6158.784442544489;6161.8459538511;6241.157443745127;6268.09112678659;6137.41694539018;6144.126041557368;6213.03697926822;6216.409441103178;6241.843953901189;6222.471089957314;6299.779507717208;6304.65324407162;6311.431971122621;6311.621302721851;4847.684900481458;4884.95115048852;4895.7062576245535;4942.258661200849;5010.774515146827;4401.867943961686;4430.4683437987005;4431.512533621299;4453.042595841909;4500.684952053325;4829.203181475087;5714.771324165325;5928.932792097464;5929.555283353766;5945;5432.076546987022;6175.475084617768;6200;6260.937390626035;6284.12022316876;5916.201312822714;5997.400259974055;5997.670128142994;6013.6692481913915;6015.969920150371;5320.201969704575;5503.889922201533;5507.834764957058;5518.944810551843;5526.309211348582;6144.525313924626;6295;6303.36966303366;6309.779157729457;6329.303776584572;6178.351273116258;6241.563090583615;6244.65654389013;6260.968695156495;6302.369763023844;6429.678516074271;6438.323975982455;6439.06633538125;6444.7744328948265;6465.515086368635;6015.969920150371;6099.9390006099375;6122.9471041527795;6253.718582663882;6254.093156492374;6209.534284928558;6235.438810507076;6246.094316324198;6294.590851594632;6301.0863424804065;6129.233845769279;6147.661878596541;6206.782762603287;6228.937710622836;6235.00239961604;6224.439800417984;6234.438900498977;6234.657093859882;6236.438720515175;6241.282252541016;5663.9716801415725;5966.343702192668;5971.761129554943;6036.094585812163;6036.426539478743;6078.300995385587;6110.16388879229;6120.0205129226715;6135.385782596045;6148.770245950738;5919.408059194218;5977.252843394575;6031.668258245839;6049.879002420015;6058.515318774399 09:13:19 INFO - PROCESS | 1645 | |1;ext.html;497.90041991601646;509.95713765349853;524.331477366358;529.1956226535665;555.8193587084198;21052.684209736864;27639.61801909904;36888.26223475532;38598;38754.61245387545;39184.80407597958;39423;39716.40425393614;39819;39877.60122398775;9226.123518265755;9418.293627977895;9419.952900235488;9430;9432.151106400423;7791.493552919071;7857;9375.859362109573;9383.014783447743;9492.098250666175;9480.620775168985;9504.667336643219;9508.191803696694;9525.428474291564;9535.380200286994;8327.791805204888;9320.533973301375;9425.481598512079;9454.149126578572;9483.525823708787;9280;9352.766180845465;9369.437833729999;9375.718728438158;9384.343095983284;37535;37769.24461510782;37810.81094594509;37903.81048094742;37954.430683539766;3163.209197700575;3197.1048106530206;3276.0990727549874;3388.15296175956;3593.6226696197045;28917.987870424433;29551.261218469495;29551.556726649116;29580;29601.555976660366;2565.7690807827385;3260.2175477885376;3267.836608169582;3298.736101111905;3307.338532293551;462880;465249;466629;467262;467350;55492.72253638706;55506.83698722612;55790.72104639532;55823.604409890075;55869;56309.43690563042;56811.14783278254;57026;57132.42867571271;57394.426055738906;15136.848631513763;15208.011479253813;15259.389624414898;15383.692326153414;15411.61470963235;12005.039596832232;12013.519459221534;12073.698157545956;12074.034077273796;12080.637580872588;12243.326617035971;15101.47144849925;15189;15264.634504890962;15403;2653.668291463567;3209.935801284008;6181.907271390933;6385.393387628168;6394.200724909387;868.6959564152496;941.4680705401427;951.0209343607005;964.3779762053437;1039.4698703661188;23314.184003559792;24244.39389015289;24286.87856560741;24346.63480047801;24472.898719557663;995.7709726762885;1045.5399624165495;1053.841923711434;1171.2562522798085;1194.8745381734793;12818.871811281768;13002.479900803863;13960.092593981359;14698;15559.533214003599;591143;934637;954617;957502;960438;44601.77699111548;47655;54822.72588637111;54851.72574137104;55580;1026.8151732688038;1119.871214810291;1301.7006088599485;1338.7791014482696;1341.637757805387;29276;29412.558811617415;29443.85278073596;30228.69771302259;30250.84874575613;47668.284975726085;48130.759346204446;48175;48305.75847120742;48386.274205886235;560038;585849;587091;589806;590506;19972.800271997676;25169.24492265235;26125.869370653025;26145.215643530726;26154.607680885183;36650.8167459161;43146.35280470733;43726.78136609297;44008;44124.77937610292;34078.82960585181;36136.27727445384;36245;36842.631573684994;37148.62851371452;35841;35994.46008309825;36508.81745591361;36662.63337366592;37040.259194815415;35079.12302192466;35151.296974059864;35675.28649427049;36061.27877442489;36113;30915.69084309218;32147.839260803546;32401;32442.513362299112;32699.67300326966;23956.041758329473;24276.514469710153;24537.618293790583;24540.509189815748;24571.508569828147 09:13:19 INFO - PROCESS | 1645 | |2;jquery.html;361.10626200154616;369.139904023625;372.7875111186624;382.357639166201;394.10931295272695;14639.780403293958;18528.629427411444;19028.14373353199;19554.413367598976;19734.90132549339;11439.59961401351;11549.65351039468;11877.7624447511;11884.346360950145;11889.92990630843;5022.773975171117;5761.4814666679995;5938.228030356069;5948.583599148062;5949.613275137124;3782.5460944686674;3980.363141897297;5579.497845193931;5811.476967072963;5879.529637629001;5939.198208241875;5941.940580594182;5945.37573554777;5955.910661340084;5960.314563825151;5691.430856914296;5867.76528938842;5914.852128696774;5917.467427931507;5918.792842250544;4994.101061808873;5691.317041954951;5723.399043100456;5729.28383952006;5736.770529178828;23736;23825.642615360783;24279.271621851374;24320;24560.877195614084;2132.4242454537343;2462.778349948513;2614.5947378156416;2879.4816932952062;2891.074856046045;8465.788355291166;9134.634614615408;9443;9521.57152928113;9905.158061564776;1366.3646404421893;1567.9216039197995;1583.90496570205;1736.157963387756;1759.780027496563;92799.07200927971;109472.35791463134;110058;126347;126514;10912.799592044868;10982.341059536453;11197.944010279896;11396.803335649804;11461.025812806005;12673.556425525247;13519.729605408034;14214.502492412923;14240.857591423954;14270.072445291024;6868.690908909111;7599.924000759922;7755.565220434238;8040.115587285394;8333.416660833684;8144.552049637208;8209.548474833942;8290.626921788535;8372.246497815226;8387.203215694499;4411.250087485135;4444.777761111932;4444.844430444984;4460.1525710114975;4500.887477813081;1269.7968325067945;1650.2408891909615;1665.9916700416663;1865.2352535460395;1909.2076788133047;860.5224100624089;877.3551439691821;922.5571725571763;957.1385752822376;1044.3107549017764;18540;19968.10143543544;20055.29806456767;20202.494937626678;20392.490187745425;904.7466709321401;918.7611221082567;920.374145580998;958.9664361747306;989.5250279865704;10118.54466549007;10371.222158338154;10485.161187105014;10551.15590752738;10558.04977552024;547182;552747;556536;567019;567315;66289.66855165693;67109.66445167742;95632;96984.03015969892;103868;908.7046709819335;916.4363916191494;938.3947353956796;940.167951363035;950.5627411390699;15681.980671256331;17205.483835484956;18457.16942737553;18517.629647407244;18619.255229790655;44948;46346.07307853892;50583.74708126583;50620.24069638885;51714;73375;73795;74531;75057;75311.87032194412;7495.175530691728;7649.464537482545;7752.689892404241;7771.300582947561;7886.566238856918;8661.826763464822;9773.218142548436;11676.591319303783;11906.285622862655;12121.393930303626;7989.041315042232;8706.695265665925;10020.294520479283;10092.293539452168;10518.842217366593;8551.05938346769;9967;9983.301168918106;10140.645077422254;10431.426271555138;10280;10541.841872371768;10563.207759418228;10697.625583104555;10757.569697212024;6756.560823546453;7513.699452021858;7620.504667196615;7724.420668450001;7908.090569584571;7262.6731797070315;7313.853722925405;7912.32745216663;10560.683179504627;10573.2598718089 09:13:19 INFO - PROCESS | 1645 | |3;mootools.html;1467.8385377608465;1510.380743895003;1518.5520271519893;1654.8250741973197;1751.903645299508;7604.201558836312;7846.607669616525;8126.024877014759;8567.528785916773;8586.570671466417;8019.318357939582;8308.418410711252;8335.66657333706;8381.706640267592;8454.02778680452;1936.99031504843;2284.3946354216146;2321.0947730385165;2321.3616255529696;2328.6507023946374;2260.7739226077424;2287.1994801819396;2344.34358379654;2352.717673879128;2354.30547988343;2075.968860467094;2207.2826331442348;2297.908083676651;2301.5627030864084;2315.4790172211287;2270.761570035139;2287.49675071484;2293.025464177718;2314.36355002374;2322.047960336271;2301.3096071178747;2306.031466783955;2333.8833058347186;2336.5677349690363;2342.8360014798973;8401.453905496122;8467.068622451525;8511.702090426867;8514.574271286472;8539.231469167742;5101.438841727407;5226.608004399685;5230.660007099526;5243.580513558944;5266.92099618506;3198.792078514912;3289.8684052637864;3527.0300667316437;3528.6647768461953;3528.6824185823143;1025.8974102589757;1041.5261056219401;1071.464267866067;1079.0881704959297;1081.6809041332826;10966.616168434066;10998.175136864687;11028.558857645685;11134.888651113548;11150.832737508943;4431.7340959542535;4517.2094883395275;4624.5375462453485;4625.838095666635;4675.7895894684825;9366.391184572982;9460.101290377403;9471.052894710474;9474.621015159317;9479.478628675352;5175.17197248446;5192.714400707921;5198.688078715213;5206.895862082737;5219.686818790885;4016.2770701273466;4689.085628302449;4733.360996265529;4792.736399497991;4809.470958194595;3708.4437334400054;3765.3975363942186;3795.848166073326;3809.066778639251;3848.5381754189675;1263.6588121207224;1264.361497443785;1269.2194300505123;1282.807578863178;1297.0790738575529;756.7237958145347;812.2202685421943;821.9671213151407;843.2811302157456;884.8584226523855;4195.517515485697;4704.364910737075;4953.761559610098;5693.117566777156;5776.653400795965;760.5170716594891;908.2280061947424;921.5208091792365;960.2605993385195;974.7563109222694;3212.550242965987;3826;3826.1773718650065;3844.2503711776494;3947.486826712509;9984.301098923152;10338;10347.844782328271;10349.223808214414;10377.325473844176;10173.33873298248;10198.490075496196;10254.077133058061;10320.174386049099;10338.379697218192;7285.23505031964;7637.42719296055;7768.65041073153;7830.686772529149;7934.841303173905;5471.8084867029465;5650.830475085754;5682.460166284362;5695.316562012585;5752.827415177551;9447.385919915183;9496.762580935532;9506.619735210514;9509;9519.143277105093;9175.862362064709;9438.95280523593;9444.669436569688;9560.139587437165;9571.1864491519;6581.111549940697;6778.966105169443;6844.965775171092;7048.577085374894;7210.278972102958;7693.422993275415;7977.521348719096;7998.720044798404;7998.84002319962;8005.199480051948;6811.829704257433;7031.859362812817;7153.4992550520965;7227.313405226495;7280.708771649075;7023.157221133497;7027.367536921702;7043.788686339418;7064.364207221375;7181.138263408425;6603.108580341593;6624.138861947916;6718.865622687616;6725.4619630430425;6740.090087838275;4736;4849.636277279147;4868.683535570177;4932.506749325039;4964.404271487445;4788.976055119702;4858.4898585648;4876.244182151772;4935.950640493549;4956.752162391794 09:13:19 INFO - PROCESS | 1645 | |4;prototype.html;289.695819389641;300.5611806762127;308.084987586867;310.574512917303;314.0578265204387;4401.8019189136485;5034.496550344964;5410.4048554659;5499.56003519718;5527.922055199233;5166.380034395877;5337.332833395825;5406.324209473816;5424.538914192299;5428;3438;3843;4103.97948010261;4153.210889930903;4168.7081904266715;3434.7423943204235;4025.677945764332;4130.628243458088;4151.169766046788;4174.833006679729;4097.910065866415;4112.753234805907;4117.929338372015;4122.690798190133;4124.608162224584;4078.8572399966006;4101.179764047202;4109.486314210739;4110.876673699794;4133.937990930139;3586.677199052072;3610.62088480711;3612.17585119513;3630.455431685242;3639.7998110103936;4979.427365852937;5216;5231;5266.315379000705;5291.4708529147165;3576.7853928764357;4731.5268473152755;4760.833370832004;4788.497207793202;4922.5478483847355;5685.630434021817;5719.19931209631;5725.3988331225455;5732.885342293131;5759.285261006669;3546.6985306249;3777.58446570877;3840.2127563849344;3868.0909986153233;3963.9405408918888;5215.921761173586;6025.216721826135;6029.3367729550155;6032.522032102119;6040.305364883051;3691.501647277637;3693.5567731872347;3761.717871159674;3933.790843797167;4000.8599699010397;3715.535558055243;3956.287868183697;4005.118873847749;4118.238125946649;4125;4618.607418369409;4635.884102897455;4648.3259927310355;4655.324977878188;4670.579647831712;5709.25779648643;5739.397363276796;5749.9425005750245;5769.365369809318;5775.249217601685;1101.8677758669012;1131.5643477261315;1172;1176.7705297466912;1216.9858883547565;669.5748199893005;821.7000794710008;840.5209030852355;861.9439736417113;863.274849126724;4303.612674859278;5149.253358263031;5150.768215430315;5173.5602473789395;5188.792448302102;4911.557959783637;4961.511546536097;5603.047481928082;5632.60614238955;5711.8000869969355;4074.3277359235394;4393.494748103945;4436.179306828247;4956.677815941952;5180.974095129575;2371.8221133415063;2617.528844807915;2921.386508833169;2940.1179646106084;2958.2604348912773;5145.716985565755;5361.924937047794;5417.374787563794;5792.189093526909;5803.506701930299;5372.570194384518;5450.5639548836;5691.82924512257;5705.686187259741;5790.594658373873;5316.202569614511;5383.542398896059;5597.6641401516035;5791.986402379567;5798.1302804579655;4975.601951843916;5185.351831021122;5291.735413229246;5308.893822123458;5617.6910269933915;4858.514148585255;5284.603654725988;5387.299651045339;5482.533984611193;5532.529734972572;5004.924926126038;5127.820526281712;5550.583706221969;5830.708464576839;5841.737121829443;3391.6947474727394;3487.163080860626;3534.2401383702627;3688.6864616506823;3713.721470889748;4912.533309335608;4970.378702662168;4995.852612853879;5507.614466987433;5877.500412464989;5007.674501157413;5050.903874891878;5348.866278343073;5387.75755091014;5681.573881958787;4289.077848262638;5651.4348565143155;5799.449052340021;5805.049823930505;5989.226787124084;5414.350277966511;5657.547396208376;5659.915101273402;5700.315962084576;5707.6290041147195;5765.769369225184;5781;5798.462937607971;5814.30228372598;5826.300843898759;5642.605017648725;5658.434156584309;5658.462446067617;5677.261955945701;5680.602357834912 09:13:19 INFO - PROCESS | 1645 | |5;yui.html;625.5227123139887;664.4285914113864;698.2947223304461;710.0237173885907;753.194082331905;13623.386947587358;14445.927770361133;14553.272336383192;14589.197594132333;14598;12432.689182770435;12492.375381230924;12531.436085376157;12533.310667913283;12541.184822986499;622.340319261582;643.2570381209711;774.5546310871272;780.8945792318035;795.7294519863245;795.129333379948;803.6986130201175;803.9799005024892;805.7179987004561;807.2976510435929;854.3250831842837;854.8247609240091;854.9953804279977;856.8500512410335;857.7255278310943;714.656964656965;844.6410275632862;848.9066202717696;849.0914721248267;851.5827244650138;622.2532960447436;633.3413250219766;633.882731694638;633.9746410143589;634.708034304216;11060.889391106148;13529.458821647122;13618.250996195206;13628.659283517893;13639.795403068962;11017.669469915916;13560;13590.388432520462;13629.318534073256;13704.65738356539;11108.277961932448;11156.274842135235;11165.944170279177;11239.83140252897;11414.54341826326;3632.6730594246646;3637.0725465006253;3649.981750091259;3656.8171591420587;3668.229671768932;61104.69447652733;86319;89280.55359723225;90796.54601727016;91255;10405.687829365132;10416.322939009091;10425.165986721044;10469;10633.415162166;20459.4885127873;20571.691424628643;20682.379528614165;20691.689624655643;20703.896480517502;7189.173245076779;7271.527350722186;7339.816504587428;7385.66764495599;7473.252674732592;3525.329908556493;4024.6981476389387;4062.6749860011128;4118.794060296973;4120.670346372283;2877.9495484148133;3235.12651584071;3340.1983523954077;3360.630330663625;3387.1193489692364;614.4009590649082;616.9722362493699;631.6273398694792;635.4947816485904;662.5991275278484;567.4580775359522;617.6818938246806;659.1562799616498;676.9966150169217;681.6285124607101;8594.914050859536;8879.866801997976;8890.4665720057;9015.368924175245;9045.366824322366;640.1454058831512;642.8268410151398;652.6475703120357;656.2518728649344;672.831792051987;4122;4205.558832714555;4243.405923170759;4290.892727681833;4436.290193569013;66868;92216;103323.4833825841;110046;113560.43219783848;84389.15610843936;86832.5658371704;94800.52599736956;96168.55747163658;96903;80405;85164.72252916335;85730.28539429177;86433.70349444763;88195.55902220577;7602.4298177638;9693.515324233618;9724.805503890024;9734.36726612768;9735.41587504752;48053;50020.24969625386;53472.73263633657;54005.729971349894;55800.441995579524;54467.72766136144;69765;70323.64838175777;73853;74778.62610686912;12077.939610301892;12273.851748085019;12312.81530777057;12337.753244935231;12808.615741527768;13514.189148651112;13679.521216757366;14113.576592702235;14130;14215.218163001135;12850.164739291917;13096.803547946598;13306.467741290628;13332.408160028455;13358.799618005543;12085.577004804789;12180.269183848997;12520.74958500843;12782.808257876326;12816.743665126458;10243.48782560884;10606;12583.182093163916;12597.307148106942;12820.423080961193;10278;10370;10446.63436779709;10491.632792852213;10786.352818830896;9276;9705.902940970782;10698.83951740709;10857;10938.562457501928 09:13:19 INFO - PROCESS | 1645 | __end_tp_report 09:13:19 INFO - PROCESS | 1645 | __start_cc_report 09:13:19 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,371 09:13:19 INFO - PROCESS | 1645 | __end_cc_report 09:13:19 INFO - PROCESS | 1645 | __startTimestamp1479748399510__endTimestamp 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | ------- Summary: start ------- 09:13:19 INFO - PROCESS | 1645 | Number of tests: 6 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5749.43 Median:6031.67 stddev:684.24 (11.3%) stddev-sans-first:659.78 09:13:19 INFO - PROCESS | 1645 | Values: 3342.5 3353.6 3379.8 3396.5 3401.0 4953.0 4955.4 5654.9 5768.8 5780.2 5626.9 5736.2 5781.6 5823.4 5927.5 5161.2 5194.6 5199.8 5267.3 5363.0 5358.8 5359.0 5364.7 5374.7 5380.4 4541.9 4974.0 5346.3 5349.6 5396.1 4274.6 5008.6 5137.2 5140.6 5163.5 5028.0 5030.2 5065.0 5091.8 5123.2 4138.9 5688.5 5729.3 5784.5 5868.4 6174.8 6205.2 6240.2 6241.5 6250.9 6125.5 6237.5 6247.1 6252.9 6283.1 5861.1 5932.0 5933.1 5936.2 6003.0 6343.4 6371.2 6372.6 6393.5 6398.4 6054.2 6103.2 6107.5 6169.1 6184.0 6117.0 6158.8 6161.8 6241.2 6268.1 6137.4 6144.1 6213.0 6216.4 6241.8 6222.5 6299.8 6304.7 6311.4 6311.6 4847.7 4885.0 4895.7 4942.3 5010.8 4401.9 4430.5 4431.5 4453.0 4500.7 4829.2 5714.8 5928.9 5929.6 5945.0 5432.1 6175.5 6200.0 6260.9 6284.1 5916.2 5997.4 5997.7 6013.7 6016.0 5320.2 5503.9 5507.8 5518.9 5526.3 6144.5 6295.0 6303.4 6309.8 6329.3 6178.4 6241.6 6244.7 6261.0 6302.4 6429.7 6438.3 6439.1 6444.8 6465.5 6016.0 6099.9 6122.9 6253.7 6254.1 6209.5 6235.4 6246.1 6294.6 6301.1 6129.2 6147.7 6206.8 6228.9 6235.0 6224.4 6234.4 6234.7 6236.4 6241.3 5664.0 5966.3 5971.8 6036.1 6036.4 6078.3 6110.2 6120.0 6135.4 6148.8 5919.4 5977.3 6031.7 6049.9 6058.5 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:74523.25 Median:37341.81 stddev:181430.26 (485.9%) stddev-sans-first:181854.08 09:13:19 INFO - PROCESS | 1645 | Values: 497.9 510.0 524.3 529.2 555.8 21052.7 27639.6 36888.3 38598.0 38754.6 39184.8 39423.0 39716.4 39819.0 39877.6 9226.1 9418.3 9420.0 9430.0 9432.2 7791.5 7857.0 9375.9 9383.0 9492.1 9480.6 9504.7 9508.2 9525.4 9535.4 8327.8 9320.5 9425.5 9454.1 9483.5 9280.0 9352.8 9369.4 9375.7 9384.3 37535.0 37769.2 37810.8 37903.8 37954.4 3163.2 3197.1 3276.1 3388.2 3593.6 28918.0 29551.3 29551.6 29580.0 29601.6 2565.8 3260.2 3267.8 3298.7 3307.3 462880.0 465249.0 466629.0 467262.0 467350.0 55492.7 55506.8 55790.7 55823.6 55869.0 56309.4 56811.1 57026.0 57132.4 57394.4 15136.8 15208.0 15259.4 15383.7 15411.6 12005.0 12013.5 12073.7 12074.0 12080.6 12243.3 15101.5 15189.0 15264.6 15403.0 2653.7 3209.9 6181.9 6385.4 6394.2 868.7 941.5 951.0 964.4 1039.5 23314.2 24244.4 24286.9 24346.6 24472.9 995.8 1045.5 1053.8 1171.3 1194.9 12818.9 13002.5 13960.1 14698.0 15559.5 591143.0 934637.0 954617.0 957502.0 960438.0 44601.8 47655.0 54822.7 54851.7 55580.0 1026.8 1119.9 1301.7 1338.8 1341.6 29276.0 29412.6 29443.9 30228.7 30250.8 47668.3 48130.8 48175.0 48305.8 48386.3 560038.0 585849.0 587091.0 589806.0 590506.0 19972.8 25169.2 26125.9 26145.2 26154.6 36650.8 43146.4 43726.8 44008.0 44124.8 34078.8 36136.3 36245.0 36842.6 37148.6 35841.0 35994.5 36508.8 36662.6 37040.3 35079.1 35151.3 35675.3 36061.3 36113.0 30915.7 32147.8 32401.0 32442.5 32699.7 23956.0 24276.5 24537.6 24540.5 24571.5 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:31640.21 Median:5008.44 stddev:92647.73 (1849.8%) stddev-sans-first:92877.86 09:13:19 INFO - PROCESS | 1645 | Values: 361.1 369.1 372.8 382.4 394.1 14639.8 18528.6 19028.1 19554.4 19734.9 11439.6 11549.7 11877.8 11884.3 11889.9 5022.8 5761.5 5938.2 5948.6 5949.6 3782.5 3980.4 5579.5 5811.5 5879.5 5939.2 5941.9 5945.4 5955.9 5960.3 5691.4 5867.8 5914.9 5917.5 5918.8 4994.1 5691.3 5723.4 5729.3 5736.8 23736.0 23825.6 24279.3 24320.0 24560.9 2132.4 2462.8 2614.6 2879.5 2891.1 8465.8 9134.6 9443.0 9521.6 9905.2 1366.4 1567.9 1583.9 1736.2 1759.8 92799.1 109472.4 110058.0 126347.0 126514.0 10912.8 10982.3 11197.9 11396.8 11461.0 12673.6 13519.7 14214.5 14240.9 14270.1 6868.7 7599.9 7755.6 8040.1 8333.4 8144.6 8209.5 8290.6 8372.2 8387.2 4411.3 4444.8 4444.8 4460.2 4500.9 1269.8 1650.2 1666.0 1865.2 1909.2 860.5 877.4 922.6 957.1 1044.3 18540.0 19968.1 20055.3 20202.5 20392.5 904.7 918.8 920.4 959.0 989.5 10118.5 10371.2 10485.2 10551.2 10558.0 547182.0 552747.0 556536.0 567019.0 567315.0 66289.7 67109.7 95632.0 96984.0 103868.0 908.7 916.4 938.4 940.2 950.6 15682.0 17205.5 18457.2 18517.6 18619.3 44948.0 46346.1 50583.7 50620.2 51714.0 73375.0 73795.0 74531.0 75057.0 75311.9 7495.2 7649.5 7752.7 7771.3 7886.6 8661.8 9773.2 11676.6 11906.3 12121.4 7989.0 8706.7 10020.3 10092.3 10518.8 8551.1 9967.0 9983.3 10140.6 10431.4 10280.0 10541.8 10563.2 10697.6 10757.6 6756.6 7513.7 7620.5 7724.4 7908.1 7262.7 7313.9 7912.3 10560.7 10573.3 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5453.89 Median:4960.58 stddev:3027.11 (61.0%) stddev-sans-first:3020.78 09:13:19 INFO - PROCESS | 1645 | Values: 1467.8 1510.4 1518.6 1654.8 1751.9 7604.2 7846.6 8126.0 8567.5 8586.6 8019.3 8308.4 8335.7 8381.7 8454.0 1937.0 2284.4 2321.1 2321.4 2328.7 2260.8 2287.2 2344.3 2352.7 2354.3 2076.0 2207.3 2297.9 2301.6 2315.5 2270.8 2287.5 2293.0 2314.4 2322.0 2301.3 2306.0 2333.9 2336.6 2342.8 8401.5 8467.1 8511.7 8514.6 8539.2 5101.4 5226.6 5230.7 5243.6 5266.9 3198.8 3289.9 3527.0 3528.7 3528.7 1025.9 1041.5 1071.5 1079.1 1081.7 10966.6 10998.2 11028.6 11134.9 11150.8 4431.7 4517.2 4624.5 4625.8 4675.8 9366.4 9460.1 9471.1 9474.6 9479.5 5175.2 5192.7 5198.7 5206.9 5219.7 4016.3 4689.1 4733.4 4792.7 4809.5 3708.4 3765.4 3795.8 3809.1 3848.5 1263.7 1264.4 1269.2 1282.8 1297.1 756.7 812.2 822.0 843.3 884.9 4195.5 4704.4 4953.8 5693.1 5776.7 760.5 908.2 921.5 960.3 974.8 3212.6 3826.0 3826.2 3844.3 3947.5 9984.3 10338.0 10347.8 10349.2 10377.3 10173.3 10198.5 10254.1 10320.2 10338.4 7285.2 7637.4 7768.7 7830.7 7934.8 5471.8 5650.8 5682.5 5695.3 5752.8 9447.4 9496.8 9506.6 9509.0 9519.1 9175.9 9439.0 9444.7 9560.1 9571.2 6581.1 6779.0 6845.0 7048.6 7210.3 7693.4 7977.5 7998.7 7998.8 8005.2 6811.8 7031.9 7153.5 7227.3 7280.7 7023.2 7027.4 7043.8 7064.4 7181.1 6603.1 6624.1 6718.9 6725.5 6740.1 4736.0 4849.6 4868.7 4932.5 4964.4 4789.0 4858.5 4876.2 4936.0 4956.8 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4530.00 Median:5147.49 stddev:1408.79 (27.4%) stddev-sans-first:1376.32 09:13:19 INFO - PROCESS | 1645 | Values: 289.7 300.6 308.1 310.6 314.1 4401.8 5034.5 5410.4 5499.6 5527.9 5166.4 5337.3 5406.3 5424.5 5428.0 3438.0 3843.0 4104.0 4153.2 4168.7 3434.7 4025.7 4130.6 4151.2 4174.8 4097.9 4112.8 4117.9 4122.7 4124.6 4078.9 4101.2 4109.5 4110.9 4133.9 3586.7 3610.6 3612.2 3630.5 3639.8 4979.4 5216.0 5231.0 5266.3 5291.5 3576.8 4731.5 4760.8 4788.5 4922.5 5685.6 5719.2 5725.4 5732.9 5759.3 3546.7 3777.6 3840.2 3868.1 3963.9 5215.9 6025.2 6029.3 6032.5 6040.3 3691.5 3693.6 3761.7 3933.8 4000.9 3715.5 3956.3 4005.1 4118.2 4125.0 4618.6 4635.9 4648.3 4655.3 4670.6 5709.3 5739.4 5749.9 5769.4 5775.2 1101.9 1131.6 1172.0 1176.8 1217.0 669.6 821.7 840.5 861.9 863.3 4303.6 5149.3 5150.8 5173.6 5188.8 4911.6 4961.5 5603.0 5632.6 5711.8 4074.3 4393.5 4436.2 4956.7 5181.0 2371.8 2617.5 2921.4 2940.1 2958.3 5145.7 5361.9 5417.4 5792.2 5803.5 5372.6 5450.6 5691.8 5705.7 5790.6 5316.2 5383.5 5597.7 5792.0 5798.1 4975.6 5185.4 5291.7 5308.9 5617.7 4858.5 5284.6 5387.3 5482.5 5532.5 5004.9 5127.8 5550.6 5830.7 5841.7 3391.7 3487.2 3534.2 3688.7 3713.7 4912.5 4970.4 4995.9 5507.6 5877.5 5007.7 5050.9 5348.9 5387.8 5681.6 4289.1 5651.4 5799.4 5805.0 5989.2 5414.4 5657.5 5659.9 5700.3 5707.6 5765.8 5781.0 5798.5 5814.3 5826.3 5642.6 5658.4 5658.5 5677.3 5680.6 09:13:19 INFO - PROCESS | 1645 | 09:13:19 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19606.23 Median:26244.65 stddev:28436.09 (108.4%) stddev-sans-first:28480.14 09:13:19 INFO - PROCESS | 1645 | Values: 625.5 664.4 698.3 710.0 753.2 13623.4 14445.9 14553.3 14589.2 14598.0 12432.7 12492.4 12531.4 12533.3 12541.2 622.3 643.3 774.6 780.9 795.7 795.1 803.7 804.0 805.7 807.3 854.3 854.8 855.0 856.9 857.7 714.7 844.6 848.9 849.1 851.6 622.3 633.3 633.9 634.0 634.7 11060.9 13529.5 13618.3 13628.7 13639.8 11017.7 13560.0 13590.4 13629.3 13704.7 11108.3 11156.3 11165.9 11239.8 11414.5 3632.7 3637.1 3650.0 3656.8 3668.2 61104.7 86319.0 89280.6 90796.5 91255.0 10405.7 10416.3 10425.2 10469.0 10633.4 20459.5 20571.7 20682.4 20691.7 20703.9 7189.2 7271.5 7339.8 7385.7 7473.3 3525.3 4024.7 4062.7 4118.8 4120.7 2877.9 3235.1 3340.2 3360.6 3387.1 614.4 617.0 631.6 635.5 662.6 567.5 617.7 659.2 677.0 681.6 8594.9 8879.9 8890.5 9015.4 9045.4 640.1 642.8 652.6 656.3 672.8 4122.0 4205.6 4243.4 4290.9 4436.3 66868.0 92216.0 103323.5 110046.0 113560.4 84389.2 86832.6 94800.5 96168.6 96903.0 80405.0 85164.7 85730.3 86433.7 88195.6 7602.4 9693.5 9724.8 9734.4 9735.4 48053.0 50020.2 53472.7 54005.7 55800.4 54467.7 69765.0 70323.6 73853.0 74778.6 12077.9 12273.9 12312.8 12337.8 12808.6 13514.2 13679.5 14113.6 14130.0 14215.2 12850.2 13096.8 13306.5 13332.4 13358.8 12085.6 12180.3 12520.7 12782.8 12816.7 10243.5 10606.0 12583.2 12597.3 12820.4 10278.0 10370.0 10446.6 10491.6 10786.4 9276.0 9705.9 10698.8 10857.0 10938.6 09:13:19 INFO - PROCESS | 1645 | -------- Summary: end -------- 09:13:19 INFO - PROCESS | 1645 | 09:13:20 INFO - TEST-INFO | 1645: exit 0 09:13:20 INFO - TEST-OK | dromaeo_css | took 1118540ms 09:13:20 INFO - TEST-START | kraken 09:13:20 INFO - operating with platform_type : mac_ 09:13:20 INFO - Initialising browser for kraken test... 09:13:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpsQgEYm/profile http://localhost:49238/getInfo.html) 09:13:23 INFO - PROCESS | 1653 | 2016-11-21 09:13:23.211 plugin-container[1654:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:13:23 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:23 INFO - PROCESS | 1653 | 2016-11-21 09:13:23.214 plugin-container[1654:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 09:13:23 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:23 INFO - PROCESS | 1653 | 2016-11-21 09:13:23.214 plugin-container[1654:10336] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:23 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:13:23 INFO - PROCESS | 1653 | colorDepth:24 09:13:23 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:13:23 INFO - PROCESS | 1653 | __metrics 09:13:23 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 09:13:24 INFO - TEST-INFO | 1653: exit 0 09:13:24 INFO - Browser initialized. 09:13:24 INFO - Running cycle 1/1 for kraken test... 09:13:24 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpsQgEYm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:26 INFO - PROCESS | 1655 | 2016-11-21 09:13:26.932 plugin-container[1656:10455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 09:13:26 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:26 INFO - PROCESS | 1655 | 2016-11-21 09:13:26.933 plugin-container[1656:10455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4313, name = 'com.apple.CFPasteboardClient' 09:13:26 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:26 INFO - PROCESS | 1655 | 2016-11-21 09:13:26.933 plugin-container[1656:10455] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:36 INFO - PROCESS | 1655 | RSS: Main: 242679808 09:13:36 INFO - PROCESS | 1655 | 09:13:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:40 INFO - PROCESS | 1655 | RSS: Main: 255127552 09:13:40 INFO - PROCESS | 1655 | 09:13:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 09:13:43 INFO - PROCESS | 1655 | RSS: Main: 262672384 09:13:43 INFO - PROCESS | 1655 | 09:13:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 09:13:47 INFO - PROCESS | 1655 | RSS: Main: 265605120 09:13:47 INFO - PROCESS | 1655 | 09:13:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 09:13:50 INFO - PROCESS | 1655 | RSS: Main: 267714560 09:13:50 INFO - PROCESS | 1655 | 09:13:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:53 INFO - PROCESS | 1655 | RSS: Main: 268251136 09:13:53 INFO - PROCESS | 1655 | 09:13:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:56 INFO - PROCESS | 1655 | RSS: Main: 273412096 09:13:56 INFO - PROCESS | 1655 | 09:14:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 09:14:00 INFO - PROCESS | 1655 | RSS: Main: 273055744 09:14:00 INFO - PROCESS | 1655 | 09:14:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 09:14:04 INFO - PROCESS | 1655 | RSS: Main: 273190912 09:14:04 INFO - PROCESS | 1655 | 09:14:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:14:06 INFO - PROCESS | 1655 | RSS: Main: 272261120 09:14:06 INFO - PROCESS | 1655 | 09:14:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:09 INFO - PROCESS | 1655 | RSS: Main: 272347136 09:14:09 INFO - PROCESS | 1655 | 09:14:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:12 INFO - PROCESS | 1655 | RSS: Main: 272424960 09:14:12 INFO - PROCESS | 1655 | 09:14:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:16 INFO - PROCESS | 1655 | RSS: Main: 272916480 09:14:16 INFO - PROCESS | 1655 | 09:14:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:19 INFO - PROCESS | 1655 | RSS: Main: 272482304 09:14:19 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 09:14:21 INFO - PROCESS | 1655 | RSS: Main: 275558400 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | __start_tp_report 09:14:21 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:14:21 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:14:21 INFO - PROCESS | 1655 | |i|pagename|runs| 09:14:21 INFO - PROCESS | 1655 | |0;ai-astar;90;129;174;117;119;120;122;124;99;174 09:14:21 INFO - PROCESS | 1655 | |1;audio-beat-detection;151;117;146;151;119;117;114;115;141;118 09:14:21 INFO - PROCESS | 1655 | |2;audio-dft;209;215;199;203;194;199;197;196;197;197 09:14:21 INFO - PROCESS | 1655 | |3;audio-fft;80;60;75;108;85;79;59;58;95;76 09:14:21 INFO - PROCESS | 1655 | |4;audio-oscillator;86;88;123;101;58;92;97;102;57;121 09:14:21 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;90;89;91;91;124;77;129;136;119 09:14:21 INFO - PROCESS | 1655 | |6;imaging-darkroom;109;95;104;109;151;152;147;109;147;95 09:14:21 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;96;92;93;93;90;123;145;134;132 09:14:21 INFO - PROCESS | 1655 | |8;json-parse-financial;55;58;56;56;60;63;78;90;89;58 09:14:21 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;68;69;78;74;76;70;71;70;74;84 09:14:21 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;100;102;81;102;107;92;94;107;110;112 09:14:21 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;147;152;157;164;166;149;167;178;247;162 09:14:21 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;178;179;174;176;177;173;174;172;175;177 09:14:21 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;68;70;70;71;69;71;69;68;70 09:14:21 INFO - PROCESS | 1655 | __end_tp_report 09:14:21 INFO - PROCESS | 1655 | __start_cc_report 09:14:21 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,941 09:14:21 INFO - PROCESS | 1655 | __end_cc_report 09:14:21 INFO - PROCESS | 1655 | __startTimestamp1479748461783__endTimestamp 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:14:21 INFO - PROCESS | 1655 | Number of tests: 14 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:126.80 Median:151.50 stddev:27.53 (18.2%) stddev-sans-first:25.78 09:14:21 INFO - PROCESS | 1655 | Values: 90.0 129.0 174.0 117.0 119.0 120.0 122.0 124.0 99.0 174.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:130.00 stddev:16.09 (12.4%) stddev-sans-first:14.95 09:14:21 INFO - PROCESS | 1655 | Values: 151.0 117.0 146.0 151.0 119.0 117.0 114.0 115.0 141.0 118.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:200.60 Median:199.00 stddev:6.60 (3.3%) stddev-sans-first:6.26 09:14:21 INFO - PROCESS | 1655 | Values: 209.0 215.0 199.0 203.0 194.0 199.0 197.0 196.0 197.0 197.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:77.50 Median:77.50 stddev:16.12 (20.8%) stddev-sans-first:17.07 09:14:21 INFO - PROCESS | 1655 | Values: 80.0 60.0 75.0 108.0 85.0 79.0 59.0 58.0 95.0 76.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:72.00 stddev:22.21 (30.8%) stddev-sans-first:23.43 09:14:21 INFO - PROCESS | 1655 | Values: 86.0 88.0 123.0 101.0 58.0 92.0 97.0 102.0 57.0 121.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:103.60 Median:89.50 stddev:20.97 (23.4%) stddev-sans-first:21.65 09:14:21 INFO - PROCESS | 1655 | Values: 90.0 90.0 89.0 91.0 91.0 124.0 77.0 129.0 136.0 119.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:121.80 Median:149.00 stddev:24.22 (16.3%) stddev-sans-first:25.24 09:14:21 INFO - PROCESS | 1655 | Values: 109.0 95.0 104.0 109.0 151.0 152.0 147.0 109.0 147.0 95.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:108.90 Median:91.50 stddev:21.86 (23.9%) stddev-sans-first:22.21 09:14:21 INFO - PROCESS | 1655 | Values: 91.0 96.0 92.0 93.0 93.0 90.0 123.0 145.0 134.0 132.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:61.50 stddev:13.91 (22.6%) stddev-sans-first:14.14 09:14:21 INFO - PROCESS | 1655 | Values: 55.0 58.0 56.0 56.0 60.0 63.0 78.0 90.0 89.0 58.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:74.00 stddev:4.93 (6.7%) stddev-sans-first:4.82 09:14:21 INFO - PROCESS | 1655 | Values: 68.0 69.0 78.0 74.0 76.0 70.0 71.0 70.0 74.0 84.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:100.70 Median:111.00 stddev:9.46 (8.5%) stddev-sans-first:10.03 09:14:21 INFO - PROCESS | 1655 | Values: 100.0 102.0 81.0 102.0 107.0 92.0 94.0 107.0 110.0 112.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:168.90 Median:165.00 stddev:29.00 (17.6%) stddev-sans-first:29.66 09:14:21 INFO - PROCESS | 1655 | Values: 147.0 152.0 157.0 164.0 166.0 149.0 167.0 178.0 247.0 162.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:176.50 stddev:2.27 (1.3%) stddev-sans-first:2.22 09:14:21 INFO - PROCESS | 1655 | Values: 178.0 179.0 174.0 176.0 177.0 173.0 174.0 172.0 175.0 177.0 09:14:21 INFO - PROCESS | 1655 | 09:14:21 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:1.34 (1.9%) stddev-sans-first:1.13 09:14:21 INFO - PROCESS | 1655 | Values: 67.0 68.0 70.0 70.0 71.0 69.0 71.0 69.0 68.0 70.0 09:14:21 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:14:21 INFO - PROCESS | 1655 | 09:14:22 INFO - TEST-INFO | 1655: exit 0 09:14:22 INFO - TEST-OK | kraken | took 62641ms 09:14:22 INFO - SUITE-END | took 1181s 09:14:22 INFO - Completed test suite (00:19:41) 09:14:22 INFO - javascript benchmark 09:14:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7010.733080084208, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3342.5487559179505, 3353.564036675232, 3379.79721216727, 3396.473546600277, 3400.979706088173, 4952.95987842553, 4955.405351357842, 5654.943450565494, 5768.798092066779, 5780.161876527906, 5626.887462250753, 5736.1682556029355, 5781.62419442736, 5823.417658234174, 5927.525797936166, 5161.200013997817, 5194.584433245332, 5199.818006369778, 5267.315249017623, 5363.034653762321, 5358.839234822962, 5359.0, 5364.704941228231, 5374.7043912584795, 5380.408155102936, 4541.931871021938, 4973.975130124362, 5346.251524786533, 5349.625526213147, 5396.082665946798, 4274.551172126944, 5008.599312055063, 5137.2037334213255, 5140.588752899759, 5163.483651634844, 5028.019536190444, 5030.195168773015, 5065.0, 5091.770870310808, 5123.1546794778815, 4138.937915931264, 5688.459596338341, 5729.341125770507, 5784.479396854261, 5868.383819698955, 6174.783882564134, 6205.22434695663, 6240.157578726859, 6241.469475094577, 6250.9062364064575, 6125.47964319526, 6237.4698150656795, 6247.094171345175, 6252.906206406908, 6283.088952101965, 5861.120831875211, 5931.970340148272, 5933.0803725421765, 5936.22829032223, 6003.0, 6343.429091381798, 6371.203599550056, 6372.58578192416, 6393.520485963479, 6398.392152745481, 6054.152418661303, 6103.155845961327, 6107.492261292505, 6169.074638804215, 6184.0, 6116.969415152896, 6158.784442544489, 6161.8459538511, 6241.157443745127, 6268.09112678659, 6137.41694539018, 6144.126041557368, 6213.03697926822, 6216.409441103178, 6241.843953901189, 6222.471089957314, 6299.779507717208, 6304.65324407162, 6311.431971122621, 6311.621302721851, 4847.684900481458, 4884.95115048852, 4895.7062576245535, 4942.258661200849, 5010.774515146827, 4401.867943961686, 4430.4683437987005, 4431.512533621299, 4453.042595841909, 4500.684952053325, 4829.203181475087, 5714.771324165325, 5928.932792097464, 5929.555283353766, 5945.0, 5432.076546987022, 6175.475084617768, 6200.0, 6260.937390626035, 6284.12022316876, 5916.201312822714, 5997.400259974055, 5997.670128142994, 6013.6692481913915, 6015.969920150371, 5320.201969704575, 5503.889922201533, 5507.834764957058, 5518.944810551843, 5526.309211348582, 6144.525313924626, 6295.0, 6303.36966303366, 6309.779157729457, 6329.303776584572, 6178.351273116258, 6241.563090583615, 6244.65654389013, 6260.968695156495, 6302.369763023844, 6429.678516074271, 6438.323975982455, 6439.06633538125, 6444.7744328948265, 6465.515086368635, 6015.969920150371, 6099.9390006099375, 6122.9471041527795, 6253.718582663882, 6254.093156492374, 6209.534284928558, 6235.438810507076, 6246.094316324198, 6294.590851594632, 6301.0863424804065, 6129.233845769279, 6147.661878596541, 6206.782762603287, 6228.937710622836, 6235.00239961604, 6224.439800417984, 6234.438900498977, 6234.657093859882, 6236.438720515175, 6241.282252541016, 5663.9716801415725, 5966.343702192668, 5971.761129554943, 6036.094585812163, 6036.426539478743, 6078.300995385587, 6110.16388879229, 6120.0205129226715, 6135.385782596045, 6148.770245950738, 5919.408059194218, 5977.252843394575, 6031.668258245839, 6049.879002420015, 6058.515318774399], "value": 5705.305450357379, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.90041991601646, 509.95713765349853, 524.331477366358, 529.1956226535665, 555.8193587084198, 21052.684209736864, 27639.61801909904, 36888.26223475532, 38598.0, 38754.61245387545, 39184.80407597958, 39423.0, 39716.40425393614, 39819.0, 39877.60122398775, 9226.123518265755, 9418.293627977895, 9419.952900235488, 9430.0, 9432.151106400423, 7791.493552919071, 7857.0, 9375.859362109573, 9383.014783447743, 9492.098250666175, 9480.620775168985, 9504.667336643219, 9508.191803696694, 9525.428474291564, 9535.380200286994, 8327.791805204888, 9320.533973301375, 9425.481598512079, 9454.149126578572, 9483.525823708787, 9280.0, 9352.766180845465, 9369.437833729999, 9375.718728438158, 9384.343095983284, 37535.0, 37769.24461510782, 37810.81094594509, 37903.81048094742, 37954.430683539766, 3163.209197700575, 3197.1048106530206, 3276.0990727549874, 3388.15296175956, 3593.6226696197045, 28917.987870424433, 29551.261218469495, 29551.556726649116, 29580.0, 29601.555976660366, 2565.7690807827385, 3260.2175477885376, 3267.836608169582, 3298.736101111905, 3307.338532293551, 462880.0, 465249.0, 466629.0, 467262.0, 467350.0, 55492.72253638706, 55506.83698722612, 55790.72104639532, 55823.604409890075, 55869.0, 56309.43690563042, 56811.14783278254, 57026.0, 57132.42867571271, 57394.426055738906, 15136.848631513763, 15208.011479253813, 15259.389624414898, 15383.692326153414, 15411.61470963235, 12005.039596832232, 12013.519459221534, 12073.698157545956, 12074.034077273796, 12080.637580872588, 12243.326617035971, 15101.47144849925, 15189.0, 15264.634504890962, 15403.0, 2653.668291463567, 3209.935801284008, 6181.907271390933, 6385.393387628168, 6394.200724909387, 868.6959564152496, 941.4680705401427, 951.0209343607005, 964.3779762053437, 1039.4698703661188, 23314.184003559792, 24244.39389015289, 24286.87856560741, 24346.63480047801, 24472.898719557663, 995.7709726762885, 1045.5399624165495, 1053.841923711434, 1171.2562522798085, 1194.8745381734793, 12818.871811281768, 13002.479900803863, 13960.092593981359, 14698.0, 15559.533214003599, 591143.0, 934637.0, 954617.0, 957502.0, 960438.0, 44601.77699111548, 47655.0, 54822.72588637111, 54851.72574137104, 55580.0, 1026.8151732688038, 1119.871214810291, 1301.7006088599485, 1338.7791014482696, 1341.637757805387, 29276.0, 29412.558811617415, 29443.85278073596, 30228.69771302259, 30250.84874575613, 47668.284975726085, 48130.759346204446, 48175.0, 48305.75847120742, 48386.274205886235, 560038.0, 585849.0, 587091.0, 589806.0, 590506.0, 19972.800271997676, 25169.24492265235, 26125.869370653025, 26145.215643530726, 26154.607680885183, 36650.8167459161, 43146.35280470733, 43726.78136609297, 44008.0, 44124.77937610292, 34078.82960585181, 36136.27727445384, 36245.0, 36842.631573684994, 37148.62851371452, 35841.0, 35994.46008309825, 36508.81745591361, 36662.63337366592, 37040.259194815415, 35079.12302192466, 35151.296974059864, 35675.28649427049, 36061.27877442489, 36113.0, 30915.69084309218, 32147.839260803546, 32401.0, 32442.513362299112, 32699.67300326966, 23956.041758329473, 24276.514469710153, 24537.618293790583, 24540.509189815748, 24571.508569828147], "value": 18737.00879161395, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.10626200154616, 369.139904023625, 372.7875111186624, 382.357639166201, 394.10931295272695, 14639.780403293958, 18528.629427411444, 19028.14373353199, 19554.413367598976, 19734.90132549339, 11439.59961401351, 11549.65351039468, 11877.7624447511, 11884.346360950145, 11889.92990630843, 5022.773975171117, 5761.4814666679995, 5938.228030356069, 5948.583599148062, 5949.613275137124, 3782.5460944686674, 3980.363141897297, 5579.497845193931, 5811.476967072963, 5879.529637629001, 5939.198208241875, 5941.940580594182, 5945.37573554777, 5955.910661340084, 5960.314563825151, 5691.430856914296, 5867.76528938842, 5914.852128696774, 5917.467427931507, 5918.792842250544, 4994.101061808873, 5691.317041954951, 5723.399043100456, 5729.28383952006, 5736.770529178828, 23736.0, 23825.642615360783, 24279.271621851374, 24320.0, 24560.877195614084, 2132.4242454537343, 2462.778349948513, 2614.5947378156416, 2879.4816932952062, 2891.074856046045, 8465.788355291166, 9134.634614615408, 9443.0, 9521.57152928113, 9905.158061564776, 1366.3646404421893, 1567.9216039197995, 1583.90496570205, 1736.157963387756, 1759.780027496563, 92799.07200927971, 109472.35791463134, 110058.0, 126347.0, 126514.0, 10912.799592044868, 10982.341059536453, 11197.944010279896, 11396.803335649804, 11461.025812806005, 12673.556425525247, 13519.729605408034, 14214.502492412923, 14240.857591423954, 14270.072445291024, 6868.690908909111, 7599.924000759922, 7755.565220434238, 8040.115587285394, 8333.416660833684, 8144.552049637208, 8209.548474833942, 8290.626921788535, 8372.246497815226, 8387.203215694499, 4411.250087485135, 4444.777761111932, 4444.844430444984, 4460.1525710114975, 4500.887477813081, 1269.7968325067945, 1650.2408891909615, 1665.9916700416663, 1865.2352535460395, 1909.2076788133047, 860.5224100624089, 877.3551439691821, 922.5571725571763, 957.1385752822376, 1044.3107549017764, 18540.0, 19968.10143543544, 20055.29806456767, 20202.494937626678, 20392.490187745425, 904.7466709321401, 918.7611221082567, 920.374145580998, 958.9664361747306, 989.5250279865704, 10118.54466549007, 10371.222158338154, 10485.161187105014, 10551.15590752738, 10558.04977552024, 547182.0, 552747.0, 556536.0, 567019.0, 567315.0, 66289.66855165693, 67109.66445167742, 95632.0, 96984.03015969892, 103868.0, 908.7046709819335, 916.4363916191494, 938.3947353956796, 940.167951363035, 950.5627411390699, 15681.980671256331, 17205.483835484956, 18457.16942737553, 18517.629647407244, 18619.255229790655, 44948.0, 46346.07307853892, 50583.74708126583, 50620.24069638885, 51714.0, 73375.0, 73795.0, 74531.0, 75057.0, 75311.87032194412, 7495.175530691728, 7649.464537482545, 7752.689892404241, 7771.300582947561, 7886.566238856918, 8661.826763464822, 9773.218142548436, 11676.591319303783, 11906.285622862655, 12121.393930303626, 7989.041315042232, 8706.695265665925, 10020.294520479283, 10092.293539452168, 10518.842217366593, 8551.05938346769, 9967.0, 9983.301168918106, 10140.645077422254, 10431.426271555138, 10280.0, 10541.841872371768, 10563.207759418228, 10697.625583104555, 10757.569697212024, 6756.560823546453, 7513.699452021858, 7620.504667196615, 7724.420668450001, 7908.090569584571, 7262.6731797070315, 7313.853722925405, 7912.32745216663, 10560.683179504627, 10573.2598718089], "value": 8793.450532312792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.8385377608465, 1510.380743895003, 1518.5520271519893, 1654.8250741973197, 1751.903645299508, 7604.201558836312, 7846.607669616525, 8126.024877014759, 8567.528785916773, 8586.570671466417, 8019.318357939582, 8308.418410711252, 8335.66657333706, 8381.706640267592, 8454.02778680452, 1936.99031504843, 2284.3946354216146, 2321.0947730385165, 2321.3616255529696, 2328.6507023946374, 2260.7739226077424, 2287.1994801819396, 2344.34358379654, 2352.717673879128, 2354.30547988343, 2075.968860467094, 2207.2826331442348, 2297.908083676651, 2301.5627030864084, 2315.4790172211287, 2270.761570035139, 2287.49675071484, 2293.025464177718, 2314.36355002374, 2322.047960336271, 2301.3096071178747, 2306.031466783955, 2333.8833058347186, 2336.5677349690363, 2342.8360014798973, 8401.453905496122, 8467.068622451525, 8511.702090426867, 8514.574271286472, 8539.231469167742, 5101.438841727407, 5226.608004399685, 5230.660007099526, 5243.580513558944, 5266.92099618506, 3198.792078514912, 3289.8684052637864, 3527.0300667316437, 3528.6647768461953, 3528.6824185823143, 1025.8974102589757, 1041.5261056219401, 1071.464267866067, 1079.0881704959297, 1081.6809041332826, 10966.616168434066, 10998.175136864687, 11028.558857645685, 11134.888651113548, 11150.832737508943, 4431.7340959542535, 4517.2094883395275, 4624.5375462453485, 4625.838095666635, 4675.7895894684825, 9366.391184572982, 9460.101290377403, 9471.052894710474, 9474.621015159317, 9479.478628675352, 5175.17197248446, 5192.714400707921, 5198.688078715213, 5206.895862082737, 5219.686818790885, 4016.2770701273466, 4689.085628302449, 4733.360996265529, 4792.736399497991, 4809.470958194595, 3708.4437334400054, 3765.3975363942186, 3795.848166073326, 3809.066778639251, 3848.5381754189675, 1263.6588121207224, 1264.361497443785, 1269.2194300505123, 1282.807578863178, 1297.0790738575529, 756.7237958145347, 812.2202685421943, 821.9671213151407, 843.2811302157456, 884.8584226523855, 4195.517515485697, 4704.364910737075, 4953.761559610098, 5693.117566777156, 5776.653400795965, 760.5170716594891, 908.2280061947424, 921.5208091792365, 960.2605993385195, 974.7563109222694, 3212.550242965987, 3826.0, 3826.1773718650065, 3844.2503711776494, 3947.486826712509, 9984.301098923152, 10338.0, 10347.844782328271, 10349.223808214414, 10377.325473844176, 10173.33873298248, 10198.490075496196, 10254.077133058061, 10320.174386049099, 10338.379697218192, 7285.23505031964, 7637.42719296055, 7768.65041073153, 7830.686772529149, 7934.841303173905, 5471.8084867029465, 5650.830475085754, 5682.460166284362, 5695.316562012585, 5752.827415177551, 9447.385919915183, 9496.762580935532, 9506.619735210514, 9509.0, 9519.143277105093, 9175.862362064709, 9438.95280523593, 9444.669436569688, 9560.139587437165, 9571.1864491519, 6581.111549940697, 6778.966105169443, 6844.965775171092, 7048.577085374894, 7210.278972102958, 7693.422993275415, 7977.521348719096, 7998.720044798404, 7998.84002319962, 8005.199480051948, 6811.829704257433, 7031.859362812817, 7153.4992550520965, 7227.313405226495, 7280.708771649075, 7023.157221133497, 7027.367536921702, 7043.788686339418, 7064.364207221375, 7181.138263408425, 6603.108580341593, 6624.138861947916, 6718.865622687616, 6725.4619630430425, 6740.090087838275, 4736.0, 4849.636277279147, 4868.683535570177, 4932.506749325039, 4964.404271487445, 4788.976055119702, 4858.4898585648, 4876.244182151772, 4935.950640493549, 4956.752162391794], "value": 4402.324427345469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.695819389641, 300.5611806762127, 308.084987586867, 310.574512917303, 314.0578265204387, 4401.8019189136485, 5034.496550344964, 5410.4048554659, 5499.56003519718, 5527.922055199233, 5166.380034395877, 5337.332833395825, 5406.324209473816, 5424.538914192299, 5428.0, 3438.0, 3843.0, 4103.97948010261, 4153.210889930903, 4168.7081904266715, 3434.7423943204235, 4025.677945764332, 4130.628243458088, 4151.169766046788, 4174.833006679729, 4097.910065866415, 4112.753234805907, 4117.929338372015, 4122.690798190133, 4124.608162224584, 4078.8572399966006, 4101.179764047202, 4109.486314210739, 4110.876673699794, 4133.937990930139, 3586.677199052072, 3610.62088480711, 3612.17585119513, 3630.455431685242, 3639.7998110103936, 4979.427365852937, 5216.0, 5231.0, 5266.315379000705, 5291.4708529147165, 3576.7853928764357, 4731.5268473152755, 4760.833370832004, 4788.497207793202, 4922.5478483847355, 5685.630434021817, 5719.19931209631, 5725.3988331225455, 5732.885342293131, 5759.285261006669, 3546.6985306249, 3777.58446570877, 3840.2127563849344, 3868.0909986153233, 3963.9405408918888, 5215.921761173586, 6025.216721826135, 6029.3367729550155, 6032.522032102119, 6040.305364883051, 3691.501647277637, 3693.5567731872347, 3761.717871159674, 3933.790843797167, 4000.8599699010397, 3715.535558055243, 3956.287868183697, 4005.118873847749, 4118.238125946649, 4125.0, 4618.607418369409, 4635.884102897455, 4648.3259927310355, 4655.324977878188, 4670.579647831712, 5709.25779648643, 5739.397363276796, 5749.9425005750245, 5769.365369809318, 5775.249217601685, 1101.8677758669012, 1131.5643477261315, 1172.0, 1176.7705297466912, 1216.9858883547565, 669.5748199893005, 821.7000794710008, 840.5209030852355, 861.9439736417113, 863.274849126724, 4303.612674859278, 5149.253358263031, 5150.768215430315, 5173.5602473789395, 5188.792448302102, 4911.557959783637, 4961.511546536097, 5603.047481928082, 5632.60614238955, 5711.8000869969355, 4074.3277359235394, 4393.494748103945, 4436.179306828247, 4956.677815941952, 5180.974095129575, 2371.8221133415063, 2617.528844807915, 2921.386508833169, 2940.1179646106084, 2958.2604348912773, 5145.716985565755, 5361.924937047794, 5417.374787563794, 5792.189093526909, 5803.506701930299, 5372.570194384518, 5450.5639548836, 5691.82924512257, 5705.686187259741, 5790.594658373873, 5316.202569614511, 5383.542398896059, 5597.6641401516035, 5791.986402379567, 5798.1302804579655, 4975.601951843916, 5185.351831021122, 5291.735413229246, 5308.893822123458, 5617.6910269933915, 4858.514148585255, 5284.603654725988, 5387.299651045339, 5482.533984611193, 5532.529734972572, 5004.924926126038, 5127.820526281712, 5550.583706221969, 5830.708464576839, 5841.737121829443, 3391.6947474727394, 3487.163080860626, 3534.2401383702627, 3688.6864616506823, 3713.721470889748, 4912.533309335608, 4970.378702662168, 4995.852612853879, 5507.614466987433, 5877.500412464989, 5007.674501157413, 5050.903874891878, 5348.866278343073, 5387.75755091014, 5681.573881958787, 4289.077848262638, 5651.4348565143155, 5799.449052340021, 5805.049823930505, 5989.226787124084, 5414.350277966511, 5657.547396208376, 5659.915101273402, 5700.315962084576, 5707.6290041147195, 5765.769369225184, 5781.0, 5798.462937607971, 5814.30228372598, 5826.300843898759, 5642.605017648725, 5658.434156584309, 5658.462446067617, 5677.261955945701, 5680.602357834912], "value": 4065.8425592445387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.5227123139887, 664.4285914113864, 698.2947223304461, 710.0237173885907, 753.194082331905, 13623.386947587358, 14445.927770361133, 14553.272336383192, 14589.197594132333, 14598.0, 12432.689182770435, 12492.375381230924, 12531.436085376157, 12533.310667913283, 12541.184822986499, 622.340319261582, 643.2570381209711, 774.5546310871272, 780.8945792318035, 795.7294519863245, 795.129333379948, 803.6986130201175, 803.9799005024892, 805.7179987004561, 807.2976510435929, 854.3250831842837, 854.8247609240091, 854.9953804279977, 856.8500512410335, 857.7255278310943, 714.656964656965, 844.6410275632862, 848.9066202717696, 849.0914721248267, 851.5827244650138, 622.2532960447436, 633.3413250219766, 633.882731694638, 633.9746410143589, 634.708034304216, 11060.889391106148, 13529.458821647122, 13618.250996195206, 13628.659283517893, 13639.795403068962, 11017.669469915916, 13560.0, 13590.388432520462, 13629.318534073256, 13704.65738356539, 11108.277961932448, 11156.274842135235, 11165.944170279177, 11239.83140252897, 11414.54341826326, 3632.6730594246646, 3637.0725465006253, 3649.981750091259, 3656.8171591420587, 3668.229671768932, 61104.69447652733, 86319.0, 89280.55359723225, 90796.54601727016, 91255.0, 10405.687829365132, 10416.322939009091, 10425.165986721044, 10469.0, 10633.415162166, 20459.4885127873, 20571.691424628643, 20682.379528614165, 20691.689624655643, 20703.896480517502, 7189.173245076779, 7271.527350722186, 7339.816504587428, 7385.66764495599, 7473.252674732592, 3525.329908556493, 4024.6981476389387, 4062.6749860011128, 4118.794060296973, 4120.670346372283, 2877.9495484148133, 3235.12651584071, 3340.1983523954077, 3360.630330663625, 3387.1193489692364, 614.4009590649082, 616.9722362493699, 631.6273398694792, 635.4947816485904, 662.5991275278484, 567.4580775359522, 617.6818938246806, 659.1562799616498, 676.9966150169217, 681.6285124607101, 8594.914050859536, 8879.866801997976, 8890.4665720057, 9015.368924175245, 9045.366824322366, 640.1454058831512, 642.8268410151398, 652.6475703120357, 656.2518728649344, 672.831792051987, 4122.0, 4205.558832714555, 4243.405923170759, 4290.892727681833, 4436.290193569013, 66868.0, 92216.0, 103323.4833825841, 110046.0, 113560.43219783848, 84389.15610843936, 86832.5658371704, 94800.52599736956, 96168.55747163658, 96903.0, 80405.0, 85164.72252916335, 85730.28539429177, 86433.70349444763, 88195.55902220577, 7602.4298177638, 9693.515324233618, 9724.805503890024, 9734.36726612768, 9735.41587504752, 48053.0, 50020.24969625386, 53472.73263633657, 54005.729971349894, 55800.441995579524, 54467.72766136144, 69765.0, 70323.64838175777, 73853.0, 74778.62610686912, 12077.939610301892, 12273.851748085019, 12312.81530777057, 12337.753244935231, 12808.615741527768, 13514.189148651112, 13679.521216757366, 14113.576592702235, 14130.0, 14215.218163001135, 12850.164739291917, 13096.803547946598, 13306.467741290628, 13332.408160028455, 13358.799618005543, 12085.577004804789, 12180.269183848997, 12520.74958500843, 12782.808257876326, 12816.743665126458, 10243.48782560884, 10606.0, 12583.182093163916, 12597.307148106942, 12820.423080961193, 10278.0, 10370.0, 10446.63436779709, 10491.632792852213, 10786.352818830896, 9276.0, 9705.902940970782, 10698.83951740709, 10857.0, 10938.562457501928], "value": 7056.11253585264, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 129.0, 174.0, 117.0, 119.0, 120.0, 122.0, 124.0, 99.0, 174.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 146.0, 151.0, 119.0, 117.0, 114.0, 115.0, 141.0, 118.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 215.0, 199.0, 203.0, 194.0, 199.0, 197.0, 196.0, 197.0, 197.0], "value": 200.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 75.0, 108.0, 85.0, 79.0, 59.0, 58.0, 95.0, 76.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 123.0, 101.0, 58.0, 92.0, 97.0, 102.0, 57.0, 121.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 91.0, 91.0, 124.0, 77.0, 129.0, 136.0, 119.0], "value": 103.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 95.0, 104.0, 109.0, 151.0, 152.0, 147.0, 109.0, 147.0, 95.0], "value": 121.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 92.0, 93.0, 93.0, 90.0, 123.0, 145.0, 134.0, 132.0], "value": 108.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 56.0, 56.0, 60.0, 63.0, 78.0, 90.0, 89.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 78.0, 74.0, 76.0, 70.0, 71.0, 70.0, 74.0, 84.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 81.0, 102.0, 107.0, 92.0, 94.0, 107.0, 110.0, 112.0], "value": 100.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 157.0, 164.0, 166.0, 149.0, 167.0, 178.0, 247.0, 162.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 179.0, 174.0, 176.0, 177.0, 173.0, 174.0, 172.0, 175.0, 177.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 70.0, 71.0, 69.0, 71.0, 69.0, 68.0, 70.0], "value": 69.3, "unit": "score"}]}]} 09:14:23 INFO - Return code: 0 09:14:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:23 ERROR - # TBPL SUCCESS # 09:14:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:23 INFO - 2016-11-21 09:14:23.115 screenresolution[1660:10839] starting screenresolution argv=screenresolution get 09:14:23 INFO - 2016-11-21 09:14:23.117 screenresolution[1660:10839] Display 0: 1600x1200x32@60 09:14:23 INFO - 2016-11-21 09:14:23.123 screenresolution[1661:10842] starting screenresolution argv=screenresolution list 09:14:23 INFO - Available Modes on Display 0 09:14:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:23 INFO - Intel Iris: 09:14:23 INFO - Chipset Model: Intel Iris 09:14:23 INFO - Type: GPU 09:14:23 INFO - Bus: Built-In 09:14:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:23 INFO - Vendor: Intel (0x8086) 09:14:23 INFO - Device ID: 0x0a2e 09:14:23 INFO - Revision ID: 0x0009 09:14:23 INFO - Displays: 09:14:23 INFO - Display: 09:14:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:23 INFO - Display Serial Number: 1600x1200 60 09:14:23 INFO - Main Display: Yes 09:14:23 INFO - Mirror: Off 09:14:23 INFO - Online: Yes 09:14:23 INFO - Rotation: Supported 09:14:23 INFO - Return code: 0 09:14:23 INFO - Running post-action listener: _resource_record_post_action 09:14:23 INFO - [mozharness: 2016-11-21 17:14:23.365345Z] Finished run-tests step (success) 09:14:23 INFO - Running post-run listener: _resource_record_post_run 09:14:23 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38496768; Write bytes: 692800000; Read time: 243; Write time: 2012 09:14:23 INFO - TinderboxPrint: CPU usage
54.4% 09:14:23 INFO - TinderboxPrint: I/O read bytes / time
38,496,768 / 243 09:14:23 INFO - TinderboxPrint: I/O write bytes / time
692,800,000 / 2,012 09:14:23 INFO - TinderboxPrint: CPU idle
2,214.3 (45.6%) 09:14:23 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 09:14:23 INFO - TinderboxPrint: CPU user
1,664.6 (34.3%) 09:14:23 INFO - TinderboxPrint: Swap in / out
422,236,160 / 0 09:14:23 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 372435456; Write bytes: 367765504; Read time: 27657; Write time: 768 09:14:23 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 32231936; Write bytes: 304671744; Read time: 189; Write time: 1190 09:14:23 INFO - Running post-run listener: _upload_blobber_files 09:14:23 INFO - Blob upload gear active. 09:14:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:23 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:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:25 INFO - (blobuploader) - INFO - Done attempting. 09:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:26 INFO - (blobuploader) - INFO - Done attempting. 09:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:29 INFO - (blobuploader) - INFO - Done attempting. 09:14:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:29 INFO - Return code: 0 09:14:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba851cbb6d10c327a9a7605950d9ca4b629014071c27f38b4af46eaa11842228a8319614633e77c203523ed4a93e5bb248124b88ed395c9513f3364e815cd413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/490ed49b4cbd4ad197397440892553b92414bdb2ab6f715c8001088d42c841e090c8e02a977d5e62eecac59c4b80c039d9a9b59467214142c80f1c8c3de9a8e0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4b73dfb5d22c6e28ee6bf69367033a02d48e76eefc844e38b8da49f30652e6d5a0cd5d7005f78af55d2ade84eb0e78e3b6eb8b4d5570c5d7c4641dedded37"} 09:14:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:29 INFO - Contents: 09:14:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba851cbb6d10c327a9a7605950d9ca4b629014071c27f38b4af46eaa11842228a8319614633e77c203523ed4a93e5bb248124b88ed395c9513f3364e815cd413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/490ed49b4cbd4ad197397440892553b92414bdb2ab6f715c8001088d42c841e090c8e02a977d5e62eecac59c4b80c039d9a9b59467214142c80f1c8c3de9a8e0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4b73dfb5d22c6e28ee6bf69367033a02d48e76eefc844e38b8da49f30652e6d5a0cd5d7005f78af55d2ade84eb0e78e3b6eb8b4d5570c5d7c4641dedded37"} 09:14:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:29 INFO - Copying logs to upload dir... 09:14:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.827063 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2016-11-21 09:14:29.121506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 09:14:29.124794) ========= 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.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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/Firefox/sha512/ba851cbb6d10c327a9a7605950d9ca4b629014071c27f38b4af46eaa11842228a8319614633e77c203523ed4a93e5bb248124b88ed395c9513f3364e815cd413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/490ed49b4cbd4ad197397440892553b92414bdb2ab6f715c8001088d42c841e090c8e02a977d5e62eecac59c4b80c039d9a9b59467214142c80f1c8c3de9a8e0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4b73dfb5d22c6e28ee6bf69367033a02d48e76eefc844e38b8da49f30652e6d5a0cd5d7005f78af55d2ade84eb0e78e3b6eb8b4d5570c5d7c4641dedded37"} build_url:https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010892 build_url: 'https://queue.taskcluster.net/v1/task/bTSVeDjSSbyZ2aTKQVfqKg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba851cbb6d10c327a9a7605950d9ca4b629014071c27f38b4af46eaa11842228a8319614633e77c203523ed4a93e5bb248124b88ed395c9513f3364e815cd413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/490ed49b4cbd4ad197397440892553b92414bdb2ab6f715c8001088d42c841e090c8e02a977d5e62eecac59c4b80c039d9a9b59467214142c80f1c8c3de9a8e0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4b73dfb5d22c6e28ee6bf69367033a02d48e76eefc844e38b8da49f30652e6d5a0cd5d7005f78af55d2ade84eb0e78e3b6eb8b4d5570c5d7c4641dedded37"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 09:14:29.166122) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:14:29.166587) ========= 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.pN4i6v86mw/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.nTIXhTdIcy/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.005142 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:14:29.216128) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 09:14:29.219755) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 09:14:29.220251) ========= ========= Total master_lag: 0.21 =========